Core: Tibber real time consumption showing as unavailable.

Created on 24 Apr 2020  路  18Comments  路  Source: home-assistant/core

The problem


According to the logs the graphql_subscription_manager has initial issues with loading data after boot. After retry it starts to retrieve data but the entity isn't updated with the values and displays as "Unavailable".

Environment

  • Home Assistant Core release with the issue: 0.108.8
  • Last working Home Assistant Core release (if known):
  • Operating environment (Home Assistant/Supervised/Docker/venv): HassOS 3.13
  • Integration causing this issue: Tibber
  • Link to integration documentation on our website: https://www.home-assistant.io/integrations/tibber/

Problem-relevant configuration.yaml

```tibber:
access_token: myTibberAccessToken

## Traceback/Error logs
<!--
  If you come across any trace or error logs, please provide them.
-->

```2020-04-24 08:34:42 DEBUG (MainThread) [graphql_subscription_manager] No websocket data in 30 seconds, checking the connection.
2020-04-24 08:35:12 DEBUG (MainThread) [graphql_subscription_manager] No websocket data in 30 seconds, checking the connection.
2020-04-24 08:35:42 DEBUG (MainThread) [graphql_subscription_manager] No websocket data in 30 seconds, checking the connection.
2020-04-24 08:36:12 DEBUG (MainThread) [graphql_subscription_manager] No websocket data in 30 seconds, checking the connection.
2020-04-24 08:36:42 DEBUG (MainThread) [graphql_subscription_manager] No websocket data in 30 seconds, checking the connection.
2020-04-24 08:37:12 DEBUG (MainThread) [graphql_subscription_manager] No websocket data in 30 seconds, checking the connection.
2020-04-24 08:37:42 DEBUG (MainThread) [graphql_subscription_manager] No websocket data in 30 seconds, checking the connection.
2020-04-24 08:38:12 DEBUG (MainThread) [graphql_subscription_manager] No websocket data in 30 seconds, checking the connection.
2020-04-24 08:38:42 DEBUG (MainThread) [graphql_subscription_manager] No websocket data in 30 seconds, checking the connection.
2020-04-24 08:39:12 DEBUG (MainThread) [graphql_subscription_manager] No websocket data in 30 seconds, checking the connection.
2020-04-24 08:39:42 ERROR (MainThread) [graphql_subscription_manager] No data, reconnecting.
2020-04-24 08:39:42 DEBUG (MainThread) [graphql_subscription_manager] Reconnecting
2020-04-24 08:39:42 DEBUG (MainThread) [graphql_subscription_manager] Retry, state: stopped
2020-04-24 08:39:42 DEBUG (MainThread) [graphql_subscription_manager] Cancel retry timer
2020-04-24 08:39:42 DEBUG (MainThread) [graphql_subscription_manager] Restart
2020-04-24 08:39:42 DEBUG (MainThread) [graphql_subscription_manager] Reconnecting to server in 15 seconds.
2020-04-24 08:39:43 DEBUG (MainThread) [graphql_subscription_manager] No websocket data in 30 seconds, checking the connection.
2020-04-24 08:39:43 DEBUG (MainThread) [graphql_subscription_manager] Reconnecting
2020-04-24 08:39:43 DEBUG (MainThread) [graphql_subscription_manager] Retry, state: stopped
2020-04-24 08:39:43 DEBUG (MainThread) [graphql_subscription_manager] Cancel retry timer
2020-04-24 08:39:43 DEBUG (MainThread) [graphql_subscription_manager] Restart
2020-04-24 08:39:43 DEBUG (MainThread) [graphql_subscription_manager] Reconnecting to server in 15 seconds.
2020-04-24 08:39:43 DEBUG (MainThread) [graphql_subscription_manager] Closing running task.
2020-04-24 08:39:58 DEBUG (MainThread) [graphql_subscription_manager] Start state starting.
2020-04-24 08:39:58 DEBUG (MainThread) [graphql_subscription_manager] Removed, 0
2020-04-24 08:39:58 DEBUG (MainThread) [graphql_subscription_manager] Add subscription <bound method TibberSensorRT._async_callback of <Entity Real time consumption My Tibber Name: None>>
2020-04-24 08:39:58 DEBUG (MainThread) [graphql_subscription_manager] Starting
2020-04-24 08:39:58 DEBUG (MainThread) [graphql_subscription_manager] Running
2020-04-24 08:39:58 DEBUG (MainThread) [graphql_subscription_manager] Recv, {'type': 'init_success'}
2020-04-24 08:39:59 DEBUG (MainThread) [graphql_subscription_manager] New subscription 1
2020-04-24 08:39:59 DEBUG (MainThread) [graphql_subscription_manager] Recv, {'type': 'subscription_success', 'id': 1}
2020-04-24 08:39:59 DEBUG (MainThread) [graphql_subscription_manager] Recv, {'type': 'subscription_data', 'id': 1, 'payload': {'data': {'liveMeasurement': {'timestamp': '2020-04-24T06:39:58+00:00', 'power': 1128.1, 'powerProduction': 0, 'accumulatedProduction': 0, 'accumulatedConsumption': 5.555382, 'accumulatedCost': 0.841977, 'currency': 'SEK', 'minPower': 268.1, 'averagePower': 641, 'maxPower': 5092.3, 'voltagePhase1': None, 'voltagePhase2': None, 'voltagePhase3': None, 'currentL1': 0.897, 'currentL2': 0.101, 'currentL3': 3.907, 'lastMeterConsumption': None, 'lastMeterProduction': None, 'signalStrength': 100}}}}
2020-04-24 08:39:59 DEBUG (MainThread) [graphql_subscription_manager] Recv, {'type': 'subscription_data', 'id': 1, 'payload': {'data': {'liveMeasurement': {'timestamp': '2020-04-24T06:39:58.5+00:00', 'power': 1131.9, 'powerProduction': 0, 'accumulatedProduction': 0, 'accumulatedConsumption': 5.555539, 'accumulatedCost': 0.842047, 'currency': 'SEK', 'minPower': 268.1, 'averagePower': 641, 'maxPower': 5092.3, 'voltagePhase1': None, 'voltagePhase2': None, 'voltagePhase3': None, 'currentL1': 0.909, 'currentL2': 0.102, 'currentL3': 3.91, 'lastMeterConsumption': None, 'lastMeterProduction': None, 'signalStrength': None}}}}
2020-04-24 08:40:00 DEBUG (MainThread) [graphql_subscription_manager] Recv, {'type': 'subscription_data', 'id': 1, 'payload': {'data': {'liveMeasurement': {'timestamp': '2020-04-24T06:39:59+00:00', 'power': 1130.1, 'powerProduction': 0, 'accumulatedProduction': 0, 'accumulatedConsumption': 5.555696, 'accumulatedCost': 0.842116, 'currency': 'SEK', 'minPower': 268.1, 'averagePower': 641.1, 'maxPower': 5092.3, 'voltagePhase1': None, 'voltagePhase2': None, 'voltagePhase3': None, 'currentL1': 0.901, 'currentL2': 0.102, 'currentL3': 3.91, 'lastMeterConsumption': None, 'lastMeterProduction': None, 'signalStrength': 100}}}}
2020-04-24 08:40:00 DEBUG (MainThread) [graphql_subscription_manager] Recv, {'type': 'subscription_data', 'id': 1, 'payload': {'data': {'liveMeasurement': {'timestamp': '2020-04-24T06:39:59.5+00:00', 'power': 1126.7, 'powerProduction': 0, 'accumulatedProduction': 0, 'accumulatedConsumption': 5.555852, 'accumulatedCost': 0.842186, 'currency': 'SEK', 'minPower': 268.1, 'averagePower': 641.1, 'maxPower': 5092.3, 'voltagePhase1': None, 'voltagePhase2': None, 'voltagePhase3': None, 'currentL1': 0.89, 'currentL2': 0.102, 'currentL3': 3.907, 'lastMeterConsumption': None, 'lastMeterProduction': None, 'signalStrength': None}}}}
2020-04-24 08:40:01 DEBUG (MainThread) [graphql_subscription_manager] Recv, {'type': 'subscription_data', 'id': 1, 'payload': {'data': {'liveMeasurement': {'timestamp': '2020-04-24T06:40:00+00:00', 'power': 1132, 'powerProduction': 0, 'accumulatedProduction': 0, 'accumulatedConsumption': 5.55601, 'accumulatedCost': 0.842255, 'currency': 'SEK', 'minPower': 268.1, 'averagePower': 641.1, 'maxPower': 5092.3, 'voltagePhase1': None, 'voltagePhase2': None, 'voltagePhase3': None, 'currentL1': 0.91, 'currentL2': 0.102, 'currentL3': 3.91, 'lastMeterConsumption': None, 'lastMeterProduction': None, 'signalStrength': None}}}}
2020-04-24 08:40:01 DEBUG (MainThread) [graphql_subscription_manager] Recv, {'type': 'subscription_data', 'id': 1, 'payload': {'data': {'liveMeasurement': {'timestamp': '2020-04-24T06:40:00.5+00:00', 'power': 1129.8, 'powerProduction': 0, 'accumulatedProduction': 0, 'accumulatedConsumption': 5.556167, 'accumulatedCost': 0.842325, 'currency': 'SEK', 'minPower': 268.1, 'averagePower': 641.1, 'maxPower': 5092.3, 'voltagePhase1': None, 'voltagePhase2': None, 'voltagePhase3': None, 'currentL1': 0.899, 'currentL2': 0.102, 'currentL3': 3.912, 'lastMeterConsumption': None, 'lastMeterProduction': None, 'signalStrength': None}}}}

Additional information

tibber stale
Source
picture pappirauk

Most helpful comment

Probably fixed in 0.109

picture Danielhiversen on 24 Apr 2020
👍2

All 18 comments

Hey there @danielhiversen, mind taking a look at this issue as its been labeled with a integration (tibber) you are listed as a codeowner for? Thanks!

probot-home-assistant[bot] picture probot-home-assistant[bot] on 24 Apr 2020

Probably fixed in 0.109

Danielhiversen picture Danielhiversen on 24 Apr 2020
👍2

Just upgraded to 0.109 - still the same issue:
2020-04-28 08:16:15 DEBUG (MainThread) [graphql_subscription_manager] No websocket data in 30 seconds, checking the connection. 2020-04-28 08:16:15 DEBUG (MainThread) [graphql_subscription_manager] Reconnecting 2020-04-28 08:16:15 DEBUG (MainThread) [graphql_subscription_manager] Retry, state: stopped 2020-04-28 08:16:15 DEBUG (MainThread) [graphql_subscription_manager] Cancel retry timer 2020-04-28 08:16:15 DEBUG (MainThread) [graphql_subscription_manager] Restart 2020-04-28 08:16:15 DEBUG (MainThread) [graphql_subscription_manager] Reconnecting to server in 15 seconds. 2020-04-28 08:16:15 DEBUG (MainThread) [graphql_subscription_manager] Closing running task. 2020-04-28 08:16:30 DEBUG (MainThread) [graphql_subscription_manager] Start state starting. 2020-04-28 08:16:30 DEBUG (MainThread) [graphql_subscription_manager] Removed, 0 2020-04-28 08:16:30 DEBUG (MainThread) [graphql_subscription_manager] Add subscription <bound method TibberSensorRT._async_callback of <Entity Real time consumption XXXXXXXXXXXXXX: None>> 2020-04-28 08:16:30 DEBUG (MainThread) [graphql_subscription_manager] Starting 2020-04-28 08:16:30 DEBUG (MainThread) [graphql_subscription_manager] Running 2020-04-28 08:16:30 DEBUG (MainThread) [graphql_subscription_manager] Recv, {'type': 'init_success'} 2020-04-28 08:16:31 DEBUG (MainThread) [graphql_subscription_manager] New subscription 1 2020-04-28 08:16:31 DEBUG (MainThread) [graphql_subscription_manager] Recv, {'type': 'subscription_success', 'id': 1} 2020-04-28 08:16:31 DEBUG (MainThread) [graphql_subscription_manager] Recv, {'type': 'subscription_data', 'id': 1, 'payload': {'data': {'liveMeasurement': {'timestamp': '2020-04-28T06:16:30.5+00:00', 'power': 1880.1, 'powerProduction': 0, 'accumulatedProduction': 0, 'accumulatedConsumption': 8.470348, 'accumulatedCost': 2.022052, 'currency': 'SEK', 'minPower': 262.6, 'averagePower': 1023.6, 'maxPower': 5590.4, 'voltagePhase1': None, 'voltagePhase2': None, 'voltagePhase3': None, 'currentL1': 1.257, 'currentL2': 0.428, 'currentL3': 6.49, 'lastMeterConsumption': None, 'lastMeterProduction': None, 'signalStrength': 100}}}} 2020-04-28 08:16:32 DEBUG (MainThread) [graphql_subscription_manager] Recv, {'type': 'subscription_data', 'id': 1, 'payload': {'data': {'liveMeasurement': {'timestamp': '2020-04-28T06:16:31+00:00', 'power': 1877, 'powerProduction': 0, 'accumulatedProduction': 0, 'accumulatedConsumption': 8.470609, 'accumulatedCost': 2.02216, 'currency': 'SEK', 'minPower': 262.6, 'averagePower': 1023.6, 'maxPower': 5590.4, 'voltagePhase1': None, 'voltagePhase2': None, 'voltagePhase3': None, 'currentL1': 1.238, 'currentL2': 0.427, 'currentL3': 6.496, 'lastMeterConsumption': None, 'lastMeterProduction': None, 'signalStrength': None}}}}

pappirauk picture pappirauk on 28 Apr 2020

I have the exact same problem.

erikkt picture erikkt on 28 Apr 2020

Could you post a screenshot showing the sensor from HA_IP/developer-tools/state ?

Danielhiversen picture Danielhiversen on 28 Apr 2020

bilde

erikkt picture erikkt on 28 Apr 2020

image

pappirauk picture pappirauk on 28 Apr 2020

Is this still an issue?

Danielhiversen picture Danielhiversen on 9 Jun 2020

Not for me, solved in latest release. Thanks for your support, and sorry for not commenting.

erikkt picture erikkt on 9 Jun 2020

I got the same problem when upgrading Home Assistant to 0-111-0. Real time consumption (Tibber Pulse) is unavailable (looks like pappirauk's screenshot above).
Soon as I downgrade to 0-110-x consumption is available again.

Electricity price from Tibber works fine in both versions.

olealm picture olealm on 15 Jun 2020

@olealm Checked the logs?
There are almost no changes in the Tibber component between 0.110 and 0.111

Danielhiversen picture Danielhiversen on 16 Jun 2020

I'm really not sure if the problem persist for me.
Sadly I've abandoned the Tibber component since it was so many issues with it.
Now I'm using the Node Red with the Tibber API Palette to be able to have full control.

pappirauk picture pappirauk on 16 Jun 2020

@Danielhiversen I'm having the same problems too. The problems have lasted a while, but I only got around to check up on it today. No log messages and the same "unavailable" message as @pappirauk reported initially. Tried to remove the integration through Configuration/Integrations and add it again. Alas, to no avail.

ronnydahl picture ronnydahl on 11 Jul 2020

Tried to enable debug logging?

    tibber: debug
    graphql_subscription_manager: debug
Danielhiversen picture Danielhiversen on 11 Jul 2020

@Danielhiversen I added debug logging at 4 pm today, and I have forty sets of the same error message that @pappirauk reported (well, kinda). Take a look here: https://paste.ubuntu.com/p/FKVgkJSTph/

ronnydahl picture ronnydahl on 13 Jul 2020

@ronnydahl Are you sure your Pulse is working? Do you get data in the Tibber app?

Danielhiversen picture Danielhiversen on 13 Jul 2020

@Danielhiversen Oh man.. I feel extremely stupid since I assumed that the Pulse was still working. You are absolutely correct. After I ran factory reset on my router a few months back, I forgot to update the Pulse WiFi settings.

For other people having the same problems, resetting you Pulse might be the solution.. ;)

ronnydahl picture ronnydahl on 14 Jul 2020
👍1

There hasn't been any activity on this issue recently. Due to the high number of incoming GitHub notifications, we have to clean some of the old issues, as many of them have already been resolved with the latest updates.
Please make sure to update to the latest Home Assistant version and check if that solves the issue. Let us know if that works for you by adding a comment 馃憤
This issue now has been marked as stale and will be closed if no further activity occurs. Thank you for your contributions.

stale[bot] picture stale[bot] on 12 Oct 2020
Was this page helpful?
0 / 5 - 0 ratings

Related issues

sibbl picture sibbl  路  3Comments
moskovskiy82 picture moskovskiy82  路  3Comments
ofuangka picture ofuangka  路  3Comments
neonandu picture neonandu  路  3Comments
YellowMonster76 picture YellowMonster76  路  3Comments