core: Tibber real time consumption showing as unavailable.

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

  access_token: myTibberAccessToken

Traceback/Error logs

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