search

home-assistant / core

:house_with_garden: Open source home automation that puts local control and privacy first.
https://www.home-assistant.io
Apache License 2.0
71.89k stars 30.11k forks source link

Miflora blocked executor thread pool #21925

Closed awarecan closed 5 years ago

awarecan commented 5 years ago

Home Assistant release with the issue:

0.89

Last working Home Assistant release (if known): n/a

Operating environment (Hass.io/Docker/Windows/etc.):

Hass.io

Component/platform:

https://www.home-assistant.io/components/sensor.miflora/

Description of problem: Discovered during investigation of #21774, might be duplicate of #19362

@daenny had report his hass.io cant load, he kindly shared his detail debug log with me. During the investigation, I found out that bad behavior of Miflora sensor might be the root cause of his deadlocked system.

The design of miflora sensor platform has flaw which will block all work threads in ThreadPoolExecutor. Its update() method need change to async_update() and use async.Lock to sequentialize all MiFloraPoller.parameter_value() calls

TL;DR; Quick recap of HA's async/sync architecture: all coroutine will be executed in single event loop (MainThread), non-coroutine will be executed in a thread pool, the size of that pool is cpu_count 5*

So, on PI, we have only 5 threads to execute all synchronized function call, such as Entity.update().

Each Miflora device have 5 monitored conditions, which will create 5 sensors. Those sensors were created at same time, so at least the first update() call will have occurred at same time. Each call will be executed in one of sync work thread. Unfortunately, miflora lib has a lock inside parameter_value() method, that caused all of 5 threads will be blocked until one call returned.

Here are the logs I got from @daenny, he has two miflora sensors, and he has 2 CPU, 10 threads in the pool. You will find all ten threads were occupied by miflora sensor.

2019-03-08 10:02:36 INFO (SyncWorker_8) [homeassistant.components.zwave] Starting Z-Wave network...
2019-03-08 10:02:36 DEBUG (SyncWorker_7) [homeassistant.helpers.storage] Writing data for core.restore_state
2019-03-08 10:02:36 DEBUG (SyncWorker_1) [homeassistant.components.sensor.miflora] Polling data for Window plant Temperature
2019-03-08 10:02:36 DEBUG (SyncWorker_6) [homeassistant.components.sensor.miflora] Polling data for Window plant Moisture
2019-03-08 10:02:36 DEBUG (SyncWorker_0) [homeassistant.components.sensor.miflora] Polling data for Window plant Conductivity
2019-03-08 10:02:36 DEBUG (SyncWorker_5) [homeassistant.components.sensor.miflora] Polling data for Window plant Light intensity
2019-03-08 10:02:36 DEBUG (SyncWorker_2) [homeassistant.components.sensor.miflora] Polling data for Window plant Battery
2019-03-08 10:02:36 DEBUG (SyncWorker_3) [homeassistant.components.sensor.miflora] Polling data for Kitchen plant Light intensity
2019-03-08 10:02:36 DEBUG (SyncWorker_8) [homeassistant.components.sensor.miflora] Polling data for Kitchen plant Temperature
2019-03-08 10:02:36 DEBUG (SyncWorker_9) [homeassistant.components.sensor.miflora] Polling data for Kitchen plant Moisture
2019-03-08 10:02:36 DEBUG (SyncWorker_4) [homeassistant.components.sensor.miflora] Polling data for Kitchen plant Conductivity
2019-03-08 10:02:36 DEBUG (SyncWorker_1) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-08 10:02:36 DEBUG (SyncWorker_7) [homeassistant.components.sensor.miflora] Polling data for Kitchen plant Battery
2019-03-08 10:02:36 DEBUG (SyncWorker_3) [miflora.miflora_poller] Filling cache with new sensor data.

No more SyncWorker thread log out after it (the last line in the log I have is 2019-03-08 10:04:44). In his case Miflora blocked all 10 threads, and cause HA unusable.

I don't have such device, hope someone can rewrite this sensor to not block our thread pool.

Problem-relevant configuration.yaml entries and (fill out even if it seems unimportant):

Traceback (if applicable):

Additional information:

ACrazyConcept commented 5 years ago

I just did a clean hassbian (89.1) install on my RPI and was going to use it only for my Mi Flora sensors. It appears that if I configure more than a handfull of sensors in my configuration.yaml, HA will not load (I get the unable to connect screen). Could this be the same issue? Strangely it seemed to work on the same HA version before I did the clean install. I have around 20 Mi Flora sensors and I'll be happy to do some tests.

awarecan commented 5 years ago

@ACrazyConcept, could you try to add PARALLEL_UPDATES = 1 to the miflora.py.

You can add it after this line, https://github.com/home-assistant/home-assistant/blob/4f49bdf262fc54a9c32224c5dc57bdd4769f9e9d/homeassistant/components/sensor/miflora.py#L31

ACrazyConcept commented 5 years ago

@ACrazyConcept, could you try to add PARALLEL_UPDATES = 1 to the miflora.py.

You can add it after this line, home-assistant/homeassistant/components/sensor/miflora.py

Line 31 in 4f49bdf

SCAN_INTERVAL = timedelta(seconds=1200)

Thanks. This doesn't seem to change anything for me. The front end only loads sometimes, whether I include PARALLEL_UPDATES = 1 or not. I am not even sure this is the same issue I am having.

awarecan commented 5 years ago

May I have your DEBUG log after you included PARALLEL_UPDATES = 1?

ACrazyConcept commented 5 years ago

May I have your DEBUG log after you included PARALLEL_UPDATES = 1?

Sure thing, which components should include in the logger? I'll try tonight.

awarecan commented 5 years ago

DEBUG for homeassistant.components.sensor.miflora miflora

INFO for rest

ACrazyConcept commented 5 years ago

Okay so I initially tried again without your modification to miflora.py. First time the front end did allow me to connect after 2½ minutes. Second time I was not allowed to connect, this is the log from the second one (10.0.0.145 is my laptop/client):

tail: /home/homeassistant/.homeassistant/home-assistant.log: file truncated
2019-03-12 18:52:57 INFO (MainThread) [homeassistant.setup] Setup of domain logger took 0.0 seconds.
2019-03-12 18:52:57 INFO (MainThread) [homeassistant.setup] Setting up recorder
2019-03-12 18:52:57 INFO (MainThread) [homeassistant.setup] Setting up http
2019-03-12 18:52:58 INFO (MainThread) [homeassistant.setup] Setup of domain http took 0.1 seconds.
2019-03-12 18:52:58 INFO (MainThread) [homeassistant.setup] Setting up lovelace
2019-03-12 18:52:58 INFO (MainThread) [homeassistant.setup] Setup of domain lovelace took 0.0 seconds.
2019-03-12 18:52:58 INFO (MainThread) [homeassistant.setup] Setting up system_log
2019-03-12 18:52:58 INFO (MainThread) [homeassistant.setup] Setup of domain system_log took 0.0 seconds.
2019-03-12 18:52:58 INFO (MainThread) [homeassistant.setup] Setting up onboarding
2019-03-12 18:52:58 INFO (MainThread) [homeassistant.setup] Setting up api
2019-03-12 18:52:58 INFO (MainThread) [homeassistant.setup] Setting up websocket_api
2019-03-12 18:52:58 INFO (MainThread) [homeassistant.setup] Setup of domain websocket_api took 0.0 seconds.
2019-03-12 18:52:58 INFO (MainThread) [homeassistant.setup] Setting up auth
2019-03-12 18:52:58 INFO (MainThread) [homeassistant.setup] Setup of domain auth took 0.0 seconds.
2019-03-12 18:52:58 INFO (MainThread) [homeassistant.setup] Setup of domain api took 0.0 seconds.
2019-03-12 18:52:58 INFO (MainThread) [homeassistant.setup] Setup of domain onboarding took 0.0 seconds.
2019-03-12 18:52:58 INFO (MainThread) [homeassistant.setup] Setting up frontend
2019-03-12 18:52:59 INFO (MainThread) [homeassistant.setup] Setup of domain frontend took 1.7 seconds.
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.setup] Setup of domain recorder took 2.3 seconds.
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.setup] Setting up history
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.setup] Setup of domain history took 0.0 seconds.
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.setup] Setting up group
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.setup] Setup of domain group took 0.0 seconds.
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.setup] Setting up person
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.setup] Setting up map
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.setup] Setup of domain map took 0.0 seconds.
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.setup] Setting up discovery
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.setup] Setup of domain discovery took 0.2 seconds.
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.setup] Setting up updater
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.setup] Setup of domain updater took 0.0 seconds.
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.loader] Loaded miflora from custom_components.miflora
2019-03-12 18:53:00 WARNING (MainThread) [homeassistant.loader] You are using a custom component for miflora which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you do experience issues with Home Assistant.
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.loader] Loaded miflora.sensor from custom_components.miflora.sensor
2019-03-12 18:53:00 WARNING (MainThread) [homeassistant.loader] You are using a custom component for miflora.sensor which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you do experience issues with Home Assistant.
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.setup] Setting up sensor
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.setup] Setting up sun
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.setup] Setup of domain sun took 0.1 seconds.
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.loader] Loaded google from homeassistant.components.google
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.loader] Loaded google.tts from homeassistant.components.google.tts
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.setup] Setting up zone
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.setup] Setup of domain zone took 0.0 seconds.
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 18:53:00 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.setup] Setting up conversation
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.setup] Setup of domain conversation took 0.0 seconds.
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.setup] Setting up webhook
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.setup] Setup of domain webhook took 0.0 seconds.
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.setup] Setting up config
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.setup] Setting up logbook
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.setup] Setup of domain logbook took 0.0 seconds.
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.setup] Setting up script
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.setup] Setup of domain script took 0.0 seconds.
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.setup] Setting up device_tracker
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.setup] Setting up system_health
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.setup] Setup of domain system_health took 0.0 seconds.
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.setup] Setting up tts
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.setup] Setting up cloud
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.setup] Setup of domain cloud took 0.0 seconds.
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.setup] Setting up automation
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.setup] Setup of domain automation took 0.0 seconds.
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.loader] Loaded config.core from homeassistant.components.config.core
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.loader] Loaded config.auth from homeassistant.components.config.auth
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.loader] Loaded config.config_entries from homeassistant.components.config.config_entries
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.loader] Loaded config.customize from homeassistant.components.config.customize
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.loader] Loaded config.script from homeassistant.components.config.script
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.loader] Loaded config.hassbian from homeassistant.components.config.hassbian
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.loader] Loaded config.auth_provider_homeassistant from homeassistant.components.config.auth_provider_homeassistant
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.loader] Loaded config.area_registry from homeassistant.components.config.area_registry
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.loader] Loaded config.group from homeassistant.components.config.group
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.loader] Loaded config.entity_registry from homeassistant.components.config.entity_registry
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.loader] Loaded config.automation from homeassistant.components.config.automation
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.loader] Loaded config.device_registry from homeassistant.components.config.device_registry
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.setup] Setup of domain device_tracker took 0.2 seconds.
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.setup] Setting up mobile_app
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.setup] Setup of domain config took 0.2 seconds.
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.setup] Setup of domain tts took 0.2 seconds.
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.setup] Setup of domain mobile_app took 0.0 seconds.
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.setup] Setup of domain sensor took 0.9 seconds.
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.setup] Setup of domain person took 1.2 seconds.
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.setup] Setting up default_config
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.setup] Setup of domain default_config took 0.0 seconds.
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.bootstrap] Home Assistant initialized in 4.26s
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.core] Starting Home Assistant
2019-03-12 18:53:01 DEBUG (Thread-12) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 18:53:01 DEBUG (Thread-5) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 18:53:01 DEBUG (Thread-20) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 18:53:01 DEBUG (Thread-17) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 18:53:01 DEBUG (Thread-21) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 18:53:01 INFO (MainThread) [homeassistant.core] Timer:starting
2019-03-12 18:53:02 INFO (MainThread) [homeassistant.components.http.view] Serving /api/websocket to 10.0.0.145 (auth: False)
2019-03-12 18:53:02 DEBUG (Thread-7) [miflora.miflora_poller] Received result for handle 56: 53 15 32 2E 37 2E 30
2019-03-12 18:53:02 DEBUG (Thread-7) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 18:53:03 DEBUG (Thread-12) [miflora.miflora_poller] Received result for handle 56: 53 15 32 2E 37 2E 30
2019-03-12 18:53:05 DEBUG (Thread-18) [miflora.miflora_poller] Received result for handle 56: 56 15 32 2E 37 2E 30
2019-03-12 18:53:08 DEBUG (Thread-4) [miflora.miflora_poller] Received result for handle 56: 39 15 32 2E 37 2E 30
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_10_light_intensity is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_10_temperature is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_10_moisture is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_11_moisture is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_11_light_intensity is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_11_temperature is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_1_temperature is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_1_moisture is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_1_light_intensity is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_12_moisture is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_12_battery is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_12_light_intensity is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_12_temperature is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_2_temperature is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_2_moisture is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_2_battery is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_2_light_intensity is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_13_moisture is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_13_temperature is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_13_battery is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_13_light_intensity is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_3_battery is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_3_light_intensity is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_3_temperature is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_3_moisture is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_14_temperature is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_14_battery is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_14_light_intensity is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_14_moisture is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_4_temperature is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_4_moisture is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_4_battery is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_4_light_intensity is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_15_moisture is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_15_temperature is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_15_light_intensity is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_15_battery is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_5_battery is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_5_light_intensity is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_5_temperature is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_5_moisture is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_16_temperature is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_16_battery is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_16_moisture is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_16_light_intensity is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_6_temperature is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_6_moisture is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_6_battery is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_6_light_intensity is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_17_moisture is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_17_light_intensity is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_17_temperature is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_17_battery is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_7_battery is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_7_light_intensity is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_7_temperature is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_7_moisture is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_18_light_intensity is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_18_battery is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_18_moisture is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_18_temperature is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_8_temperature is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_8_moisture is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_8_battery is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_8_light_intensity is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_19_moisture is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_19_light_intensity is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_19_battery is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_19_temperature is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_9_light_intensity is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_9_temperature is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_9_battery is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_9_moisture is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_20_battery is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_20_light_intensity is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_20_moisture is taking over 10 seconds
2019-03-12 18:53:11 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_20_temperature is taking over 10 seconds
2019-03-12 18:53:12 DEBUG (Thread-5) [miflora.miflora_poller] Received result for handle 56: 56 15 32 2E 37 2E 30
2019-03-12 18:53:14 DEBUG (Thread-2) [miflora.miflora_poller] Received result for handle 56: 50 15 32 2E 37 2E 30
2019-03-12 18:53:16 DEBUG (Thread-14) [miflora.miflora_poller] Received result for handle 56: 59 15 32 2E 37 2E 30
2019-03-12 18:53:19 DEBUG (Thread-20) [miflora.miflora_poller] Received result for handle 56: 3A 15 32 2E 37 2E 30
2019-03-12 18:53:26 DEBUG (Thread-17) [miflora.miflora_poller] Received result for handle 56: 50 15 32 2E 37 2E 30
2019-03-12 18:53:29 DEBUG (Thread-21) [miflora.miflora_poller] Received result for handle 56: 59 15 32 2E 37 2E 30
2019-03-12 18:53:30 DEBUG (Thread-7) [miflora.miflora_poller] Received result for handle 56: 58 15 32 2E 37 2E 30
2019-03-12 18:53:31 DEBUG (Thread-7) [miflora.miflora_poller] Received result for handle 53: BD 00 00 5F 00 00 00 0A 86 00 02 3C 00 FB 34 9B
2019-03-12 18:53:31 DEBUG (Thread-18) [miflora.miflora_poller] Using cache (0:00:00.012829 < 0:20:12)
2019-03-12 18:53:31 DEBUG (Thread-7) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 18:53:31 DEBUG (Thread-2) [miflora.miflora_poller] Using cache (0:00:00.026896 < 0:20:12)
2019-03-12 18:53:31 DEBUG (Thread-4) [miflora.miflora_poller] Received result for handle 56: 58 15 32 2E 37 2E 30
2019-03-12 18:53:31 DEBUG (Thread-4) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 18:53:33 DEBUG (Thread-5) [miflora.miflora_poller] Received result for handle 53: C9 00 00 38 00 00 00 09 3E 00 02 3C 00 FB 34 9B
2019-03-12 18:53:33 DEBUG (Thread-10) [miflora.miflora_poller] Using cache (0:00:00.004010 < 0:20:10)
2019-03-12 18:53:33 DEBUG (Thread-6) [miflora.miflora_poller] Using cache (0:00:00.029146 < 0:20:10)
2019-03-12 18:53:37 DEBUG (Thread-14) [miflora.miflora_poller] Received result for handle 56: 64 15 32 2E 37 2E 30
2019-03-12 18:53:37 DEBUG (Thread-14) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 18:53:38 DEBUG (Thread-20) [miflora.miflora_poller] Received result for handle 53: CA 00 00 E9 00 00 00 0B 06 00 02 3C 00 FB 34 9B
2019-03-12 18:53:38 DEBUG (Thread-3) [miflora.miflora_poller] Using cache (0:00:00.003841 < 0:20:00)
2019-03-12 18:53:38 DEBUG (Thread-8) [miflora.miflora_poller] Using cache (0:00:00.011413 < 0:20:00)
2019-03-12 18:53:42 DEBUG (Thread-17) [miflora.miflora_poller] Received result for handle 53: B7 00 00 51 00 00 00 0B 08 00 02 3C 00 FB 34 9B
2019-03-12 18:53:42 DEBUG (Thread-17) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 18:53:42 DEBUG (Thread-13) [miflora.miflora_poller] Using cache (0:00:00.020234 < 0:20:11)
2019-03-12 18:53:42 DEBUG (Thread-11) [miflora.miflora_poller] Using cache (0:00:00.049361 < 0:20:11)
2019-03-12 18:53:43 DEBUG (Thread-21) [miflora.miflora_poller] Received result for handle 53: C2 00 00 97 00 00 00 0D 94 00 02 3C 00 FB 34 9B
2019-03-12 18:53:43 DEBUG (Thread-9) [miflora.miflora_poller] Using cache (0:00:00.003514 < 0:20:01)
2019-03-12 18:53:43 DEBUG (Thread-19) [miflora.miflora_poller] Using cache (0:00:00.014532 < 0:20:01)
2019-03-12 18:53:43 DEBUG (Thread-9) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 18:53:45 DEBUG (Thread-12) [miflora.miflora_poller] Received result for handle 53: AE 00 00 38 00 00 00 0A 49 00 02 3C 00 FB 34 9B
2019-03-12 18:53:45 DEBUG (Thread-15) [miflora.miflora_poller] Using cache (0:00:00.002355 < 0:20:09)
2019-03-12 18:53:45 DEBUG (Thread-16) [miflora.miflora_poller] Using cache (0:00:00.023319 < 0:20:09)
2019-03-12 18:53:45 DEBUG (Thread-12) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 18:53:47 DEBUG (Thread-7) [miflora.miflora_poller] Received result for handle 56: 64 15 32 2E 37 2E 30
2019-03-12 18:53:47 DEBUG (Thread-7) [miflora.miflora_poller] Received result for handle 53: B7 00 00 32 00 00 00 13 A0 00 02 3C 00 FB 34 9B
2019-03-12 18:53:47 DEBUG (Thread-18) [miflora.miflora_poller] Using cache (0:00:00.003851 < 0:20:02)
2019-03-12 18:53:47 DEBUG (Thread-2) [miflora.miflora_poller] Using cache (0:00:00.026071 < 0:20:02)
2019-03-12 18:53:54 DEBUG (Thread-5) [miflora.miflora_poller] Received result for handle 56: 64 15 32 2E 37 2E 30
2019-03-12 18:53:54 DEBUG (Thread-5) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 18:53:55 DEBUG (Thread-14) [miflora.miflora_poller] Received result for handle 56: 64 15 32 2E 37 2E 30
2019-03-12 18:53:55 DEBUG (Thread-14) [miflora.miflora_poller] Received result for handle 53: AE 00 00 18 00 00 00 15 40 00 02 3C 00 FB 34 9B
2019-03-12 18:53:55 DEBUG (Thread-20) [miflora.miflora_poller] Using cache (0:00:00.003719 < 0:20:03)
2019-03-12 18:53:55 DEBUG (Thread-8) [miflora.miflora_poller] Using cache (0:00:00.008225 < 0:20:03)
2019-03-12 18:53:57 DEBUG (Thread-17) [miflora.miflora_poller] Received result for handle 56: 64 15 32 2E 37 2E 30
2019-03-12 18:53:58 DEBUG (Thread-21) [miflora.miflora_poller] Received result for handle 56: 64 15 32 2E 37 2E 30
2019-03-12 18:53:58 DEBUG (Thread-21) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 18:54:03 DEBUG (Thread-19) [miflora.miflora_poller] Received result for handle 56: 5B 15 32 2E 37 2E 30
2019-03-12 18:54:03 DEBUG (Thread-9) [miflora.miflora_poller] Received result for handle 56: 5B 15 32 2E 37 2E 30
2019-03-12 18:54:03 DEBUG (Thread-9) [miflora.miflora_poller] Received result for handle 53: B5 00 00 37 00 00 00 10 19 00 02 3C 00 FB 34 9B
2019-03-12 18:54:03 DEBUG (Thread-15) [miflora.miflora_poller] Using cache (0:00:00.002442 < 0:20:04)
2019-03-12 18:54:03 DEBUG (Thread-16) [miflora.miflora_poller] Using cache (0:00:00.032444 < 0:20:04)
2019-03-12 18:54:05 DEBUG (Thread-4) [miflora.miflora_poller] Received result for handle 56: 64 15 32 2E 37 2E 30
2019-03-12 18:54:07 DEBUG (Thread-7) [miflora.miflora_poller] Received result for handle 56: 56 15 32 2E 37 2E 30
2019-03-12 18:54:07 DEBUG (Thread-7) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 18:54:10 DEBUG (Thread-5) [miflora.miflora_poller] Received result for handle 56: 59 15 32 2E 37 2E 30
2019-03-12 18:54:11 DEBUG (Thread-5) [miflora.miflora_poller] Received result for handle 53: C0 00 00 75 00 00 00 17 0B 00 02 3C 00 FB 34 9B
2019-03-12 18:54:11 DEBUG (Thread-14) [miflora.miflora_poller] Using cache (0:00:00.002617 < 0:20:05)
2019-03-12 18:54:11 DEBUG (Thread-20) [miflora.miflora_poller] Using cache (0:00:00.024252 < 0:20:05)
2019-03-12 18:54:11 DEBUG (Thread-20) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 18:54:11 DEBUG (Thread-8) [miflora.miflora_poller] Received result for handle 56: 59 15 32 2E 37 2E 30
2019-03-12 18:54:15 DEBUG (Thread-17) [miflora.miflora_poller] Received result for handle 53: B9 00 00 5C 00 00 00 0A 1D 00 02 3C 00 FB 34 9B
2019-03-12 18:54:15 DEBUG (Thread-13) [miflora.miflora_poller] Using cache (0:00:00.002424 < 0:20:14)
2019-03-12 18:54:15 DEBUG (Thread-11) [miflora.miflora_poller] Using cache (0:00:00.022851 < 0:20:14)
2019-03-12 18:54:15 DEBUG (Thread-17) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 18:54:21 DEBUG (Thread-21) [miflora.miflora_poller] Received result for handle 56: 4F 15 32 2E 37 2E 30
2019-03-12 18:54:21 DEBUG (Thread-21) [miflora.miflora_poller] Received result for handle 53: BA 00 00 8C 00 00 00 0B 37 00 02 3C 00 FB 34 9B
2019-03-12 18:54:21 DEBUG (Thread-19) [miflora.miflora_poller] Using cache (0:00:00.003816 < 0:20:16)
2019-03-12 18:54:21 DEBUG (Thread-15) [miflora.miflora_poller] Using cache (0:00:00.028645 < 0:20:16)
2019-03-12 18:54:21 DEBUG (Thread-16) [miflora.miflora_poller] Received result for handle 56: 4F 15 32 2E 37 2E 30
2019-03-12 18:54:21 DEBUG (Thread-16) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 18:54:24 DEBUG (Thread-9) [miflora.miflora_poller] Received result for handle 56: 64 15 32 2E 37 2E 30
2019-03-12 18:54:26 INFO (MainThread) [homeassistant.components.websocket_api.http.connection.1765465264] Connection closed by client
2019-03-12 18:54:30 INFO (MainThread) [homeassistant.components.http.view] Serving /api/websocket to 10.0.0.145 (auth: False)
2019-03-12 18:54:30 INFO (MainThread) [homeassistant.components.websocket_api.http.connection.1765412208] Connection closed by client
2019-03-12 18:54:32 DEBUG (Thread-4) [miflora.miflora_poller] Received result for handle 53: B1 00 00 6E 00 00 00 0B 28 00 02 3C 00 FB 34 9B
2019-03-12 18:54:32 DEBUG (Thread-10) [miflora.miflora_poller] Using cache (0:00:00.003630 < 0:20:13)
2019-03-12 18:54:32 DEBUG (Thread-6) [miflora.miflora_poller] Using cache (0:00:00.029147 < 0:20:13)
2019-03-12 18:54:32 DEBUG (Thread-6) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 18:54:35 INFO (MainThread) [homeassistant.components.http.view] Serving /api/websocket to 10.0.0.145 (auth: False)
2019-03-12 18:54:35 INFO (MainThread) [homeassistant.components.websocket_api.http.connection.1765411696] Connection closed by client
2019-03-12 18:54:40 INFO (MainThread) [homeassistant.components.http.view] Serving /api/websocket to 10.0.0.145 (auth: False)
2019-03-12 18:54:40 INFO (MainThread) [homeassistant.components.websocket_api.http.connection.1765396304] Connection closed by client
2019-03-12 18:54:45 INFO (MainThread) [homeassistant.components.http.view] Serving /api/websocket to 10.0.0.145 (auth: False)
2019-03-12 18:54:45 INFO (MainThread) [homeassistant.components.websocket_api.http.connection.1765393104] Connection closed by client
2019-03-12 18:54:50 INFO (MainThread) [homeassistant.components.http.view] Serving /api/websocket to 10.0.0.145 (auth: False)
2019-03-12 18:54:50 INFO (MainThread) [homeassistant.components.websocket_api.http.connection.1765375152] Connection closed by client
2019-03-12 18:54:54 DEBUG (Thread-7) [miflora.miflora_poller] Received result for handle 56: 64 15 32 2E 37 2E 30
2019-03-12 18:54:55 INFO (MainThread) [homeassistant.components.http.view] Serving /api/websocket to 10.0.0.145 (auth: False)
2019-03-12 18:54:55 INFO (MainThread) [homeassistant.components.websocket_api.http.connection.1765374896] Connection closed by client
2019-03-12 18:54:56 DEBUG (Thread-3) [miflora.miflora_poller] Received result for handle 56: 64 15 32 2E 37 2E 30
2019-03-12 18:55:00 INFO (MainThread) [homeassistant.components.http.view] Serving /api/websocket to 10.0.0.145 (auth: False)
2019-03-12 18:55:00 INFO (MainThread) [homeassistant.components.websocket_api.http.connection.1765394640] Connection closed by client
2019-03-12 18:55:02 DEBUG (Thread-20) [miflora.miflora_poller] Received result for handle 56: 62 15 32 2E 37 2E 30
2019-03-12 18:55:02 DEBUG (Thread-8) [miflora.miflora_poller] Received result for handle 56: 62 15 32 2E 37 2E 30
2019-03-12 18:55:04 DEBUG (Thread-17) [miflora.miflora_poller] Received result for handle 56: 58 15 32 2E 37 2E 30
2019-03-12 18:55:05 INFO (MainThread) [homeassistant.components.http.view] Serving /api/websocket to 10.0.0.145 (auth: False)
2019-03-12 18:55:05 INFO (MainThread) [homeassistant.components.websocket_api.http.connection.1765393840] Connection closed by client
2019-03-12 18:55:06 DEBUG (Thread-12) [miflora.miflora_poller] Received result for handle 56: 57 15 32 2E 37 2E 30
2019-03-12 18:55:10 INFO (MainThread) [homeassistant.components.http.view] Serving /api/websocket to 10.0.0.145 (auth: False)
2019-03-12 18:55:10 INFO (MainThread) [homeassistant.components.websocket_api.http.connection.1765411600] Connection closed by client
2019-03-12 18:55:10 DEBUG (Thread-21) [miflora.miflora_poller] Received result for handle 56: 58 15 32 2E 37 2E 30
2019-03-12 18:55:15 INFO (MainThread) [homeassistant.components.http.view] Serving /api/websocket to 10.0.0.145 (auth: False)
2019-03-12 18:55:15 INFO (MainThread) [homeassistant.components.websocket_api.http.connection.1765410096] Connection closed by client
2019-03-12 18:55:20 INFO (MainThread) [homeassistant.components.http.view] Serving /api/websocket to 10.0.0.145 (auth: False)
2019-03-12 18:55:20 INFO (MainThread) [homeassistant.components.websocket_api.http.connection.1765465968] Connection closed by client
2019-03-12 18:55:25 INFO (MainThread) [homeassistant.components.http.view] Serving /api/websocket to 10.0.0.145 (auth: False)
2019-03-12 18:55:25 INFO (MainThread) [homeassistant.components.websocket_api.http.connection.1765374096] Connection closed by client
2019-03-12 18:55:26 DEBUG (Thread-16) [miflora.miflora_poller] Received result for handle 56: 64 15 32 2E 37 2E 30
2019-03-12 18:55:26 DEBUG (Thread-4) [miflora.miflora_poller] Received result for handle 56: 64 15 32 2E 37 2E 30
2019-03-12 18:55:30 INFO (MainThread) [homeassistant.components.http.view] Serving /api/websocket to 10.0.0.145 (auth: False)
2019-03-12 18:55:30 INFO (MainThread) [homeassistant.components.websocket_api.http.connection.1765373488] Connection closed by client
2019-03-12 18:55:30 DEBUG (Thread-6) [miflora.miflora_poller] Received result for handle 56: 52 15 32 2E 37 2E 30
2019-03-12 18:55:31 DEBUG (Thread-6) [miflora.miflora_poller] Received result for handle 53: B7 00 00 4F 00 00 00 0B 41 00 02 3C 00 FB 34 9B
2019-03-12 18:55:31 DEBUG (Thread-6) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 18:55:31 DEBUG (Thread-3) [miflora.miflora_poller] Using cache (0:00:00.005336 < 0:20:08)
2019-03-12 18:55:31 DEBUG (Thread-21) [miflora.miflora_poller] Using cache (0:00:00.033857 < 0:20:08)
2019-03-12 18:55:33 DEBUG (Thread-20) [miflora.miflora_poller] Received result for handle 53: B6 00 00 37 00 00 00 0E 4B 00 02 3C 00 FB 34 9B
2019-03-12 18:55:33 DEBUG (Thread-13) [miflora.miflora_poller] Using cache (0:00:00.004134 < 0:20:17)
2019-03-12 18:55:33 DEBUG (Thread-11) [miflora.miflora_poller] Using cache (0:00:00.007871 < 0:20:17)
2019-03-12 18:55:33 INFO (MainThread) [homeassistant.components.http.view] Serving /api/websocket to 10.0.0.145 (auth: False)
2019-03-12 18:55:33 INFO (MainThread) [homeassistant.components.websocket_api.http.connection.1833018704] Connection closed by client
2019-03-12 18:55:35 DEBUG (Thread-8) [miflora.miflora_poller] Received result for handle 56: 52 15 32 2E 37 2E 30
2019-03-12 18:55:39 INFO (MainThread) [homeassistant.components.http.view] Serving /api/websocket to 10.0.0.145 (auth: False)
2019-03-12 18:55:39 INFO (MainThread) [homeassistant.components.websocket_api.http.connection.1630707792] Connection closed by client
2019-03-12 18:55:46 INFO (MainThread) [homeassistant.components.discovery] Unknown service discovered: dlna_dms {'ssdp_description': 'http://10.0.0.148:60006/upnp/desc/aios_device/aios_device.xml', 'port': 60006, 'upnp_device_type': 'urn:schemas-denon-com:device:AiosDevice:1', 'udn': 'uuid:c34d81e9-7437-1dc5-0080-0005cdc485e6', 'serial': 'BCS27180800161', 'name': 'Marantz SR7013', 'manufacturer': 'Marantz', 'model_number': 'Aios 4.025', 'host': '10.0.0.148', 'model_name': 'Marantz SR7013'}
2019-03-12 18:55:46 INFO (MainThread) [homeassistant.components.discovery] Unknown service discovered: spotify_connect {'port': 80, 'properties': {'CPath': '/spotify', 'VERSION': '1.0'}, 'hostname': 'Marantz-SR7013.local.', 'host': '10.0.0.148'}
2019-03-12 18:55:46 INFO (MainThread) [homeassistant.loader] Loaded cast from homeassistant.components.cast
2019-03-12 18:55:46 INFO (MainThread) [homeassistant.loader] Loaded persistent_notification from homeassistant.components.persistent_notification
2019-03-12 18:55:46 INFO (MainThread) [homeassistant.components.discovery] Unknown service discovered: homekit {'name': 'tado Internet Bridge IB1393427968', 'port': 3000, 'properties': {'md': 'tado Internet Bridge', 'sf': '0', 's#': '1', 'ff': '1', 'pv': '1.0', 'c#': '22', 'id': '23:03:45:4e:a3:42', 'ci': '2'}, 'hostname': 'tadoBridgeIB1393427968.local.', 'host': '10.0.0.40'}
2019-03-12 18:55:46 INFO (MainThread) [homeassistant.components.discovery] Unknown service discovered: dlna_dmr {'ssdp_description': 'http://10.0.0.131:52323/MediaRenderer.xml', 'port': 52323, 'upnp_device_type': 'urn:schemas-upnp-org:device:MediaRenderer:1', 'udn': 'uuid:23456789-1234-1010-8000-cc988b6db375', 'serial': None, 'name': 'KD-65XF9005', 'manufacturer': 'Sony Corporation', 'model_number': '100', 'host': '10.0.0.131', 'model_name': 'MediaRenderer'}
2019-03-12 18:55:46 INFO (MainThread) [homeassistant.components.discovery] Unknown service discovered: dlna_dmr {'ssdp_description': 'http://10.0.0.148:60006/upnp/desc/aios_device/aios_device.xml', 'port': 60006, 'upnp_device_type': 'urn:schemas-denon-com:device:AiosDevice:1', 'udn': 'uuid:c34d81e9-7437-1dc5-0080-0005cdc485e6', 'serial': 'BCS27180800161', 'name': 'Marantz SR7013', 'manufacturer': 'Marantz', 'model_number': 'Aios 4.025', 'host': '10.0.0.148', 'model_name': 'Marantz SR7013'}
2019-03-12 18:55:50 INFO (MainThread) [homeassistant.components.http.view] Serving /api/websocket to 10.0.0.145 (auth: False)
2019-03-12 18:55:50 INFO (MainThread) [homeassistant.components.websocket_api.http.connection.1765362416] Connection closed by client
2019-03-12 18:55:51 DEBUG (Thread-17) [miflora.miflora_poller] Received result for handle 53: BB 00 00 27 00 00 00 11 AA 00 02 3C 00 FB 34 9B
2019-03-12 18:55:51 DEBUG (Thread-19) [miflora.miflora_poller] Using cache (0:00:00.003704 < 0:20:07)
2019-03-12 18:55:51 DEBUG (Thread-15) [miflora.miflora_poller] Using cache (0:00:00.019231 < 0:20:07)
2019-03-12 18:55:51 INFO (MainThread) [homeassistant.components.http.view] Serving /api/websocket to 10.0.0.145 (auth: False)
2019-03-12 18:55:51 INFO (MainThread) [homeassistant.components.websocket_api.http.connection.1833392560] Connection closed by client
2019-03-12 18:55:52 INFO (MainThread) [homeassistant.components.http.view] Serving /api/websocket to 10.0.0.145 (auth: False)
2019-03-12 18:55:52 INFO (MainThread) [homeassistant.components.websocket_api.http.connection.1833191248] Connection closed by client
2019-03-12 18:55:54 DEBUG (Thread-12) [miflora.miflora_poller] Received result for handle 53: D4 00 00 76 00 00 00 0F 2C 00 02 3C 00 FB 34 9B
2019-03-12 18:55:54 DEBUG (Thread-18) [miflora.miflora_poller] Using cache (0:00:00.003769 < 0:20:15)
2019-03-12 18:55:54 DEBUG (Thread-2) [miflora.miflora_poller] Using cache (0:00:00.028296 < 0:20:15)
2019-03-12 18:55:59 DEBUG (Thread-16) [miflora.miflora_poller] Received result for handle 53: B3 00 00 27 00 00 00 13 3A 00 02 3C 00 FB 34 9B
2019-03-12 18:55:59 DEBUG (Thread-9) [miflora.miflora_poller] Using cache (0:00:00.005291 < 0:20:18)
2019-03-12 18:55:59 DEBUG (Thread-10) [miflora.miflora_poller] Using cache (0:00:00.024470 < 0:20:18)
2019-03-12 18:56:01 DEBUG (Thread-4) [miflora.miflora_poller] Received result for handle 56: 5D 15 32 2E 37 2E 30
2019-03-12 18:56:11 DEBUG (Thread-7) [miflora.miflora_poller] Received result for handle 53: AF 00 00 82 00 00 00 0B 57 00 02 3C 00 FB 34 9B
2019-03-12 18:56:11 DEBUG (Thread-14) [miflora.miflora_poller] Using cache (0:00:00.003626 < 0:20:06)
2019-03-12 18:56:11 DEBUG (Thread-5) [miflora.miflora_poller] Using cache (0:00:00.035907 < 0:20:06)
2019-03-12 18:56:12 DEBUG (Thread-6) [miflora.miflora_poller] Received result for handle 56: 5D 15 32 2E 37 2E 30
2019-03-12 18:56:13 DEBUG (Thread-6) [miflora.miflora_poller] Received result for handle 53: B3 00 00 27 00 00 00 09 00 00 02 3C 00 FB 34 9B
2019-03-12 18:56:13 DEBUG (Thread-3) [miflora.miflora_poller] Using cache (0:00:00.002277 < 0:20:19)
2019-03-12 18:56:13 DEBUG (Thread-21) [miflora.miflora_poller] Using cache (0:00:00.007567 < 0:20:19)
2019-03-12 18:57:40 INFO (MainThread) [homeassistant.components.http.view] Serving /api/websocket to 10.0.0.145 (auth: False)
2019-03-12 18:57:40 INFO (MainThread) [homeassistant.components.websocket_api.http.connection.1765360944] Connection closed by client
2019-03-12 18:58:59 INFO (MainThread) [homeassistant.components.http.view] Serving /api/websocket to 10.0.0.145 (auth: False)
2019-03-12 18:59:00 INFO (MainThread) [homeassistant.components.websocket_api.http.connection.1630710992] Connection closed by client
2019-03-12 18:59:14 INFO (MainThread) [homeassistant.components.http.view] Serving /api/websocket to 10.0.0.145 (auth: False)
2019-03-12 18:59:14 INFO (MainThread) [homeassistant.components.websocket_api.http.connection.1765442992] Connection closed by client
2019-03-12 18:59:17 INFO (MainThread) [homeassistant.components.http.view] Serving /api/websocket to 10.0.0.145 (auth: False)
2019-03-12 18:59:17 INFO (MainThread) [homeassistant.components.websocket_api.http.connection.1833020496] Connection closed by client
2019-03-12 18:59:22 INFO (MainThread) [homeassistant.components.http.view] Serving /api/websocket to 10.0.0.145 (auth: False)
2019-03-12 18:59:22 INFO (MainThread) [homeassistant.components.websocket_api.http.connection.1816108880] Connection closed by client

First restart after adding PARALLEL_UPDATES=1:

tail: /home/homeassistant/.homeassistant/home-assistant.log: file truncated
2019-03-12 19:10:09 INFO (MainThread) [homeassistant.setup] Setup of domain logger took 0.0 seconds.
2019-03-12 19:10:09 INFO (MainThread) [homeassistant.setup] Setting up http
2019-03-12 19:10:09 INFO (MainThread) [homeassistant.setup] Setup of domain http took 0.1 seconds.
2019-03-12 19:10:09 INFO (MainThread) [homeassistant.setup] Setting up lovelace
2019-03-12 19:10:09 INFO (MainThread) [homeassistant.setup] Setup of domain lovelace took 0.0 seconds.
2019-03-12 19:10:09 INFO (MainThread) [homeassistant.setup] Setting up system_log
2019-03-12 19:10:09 INFO (MainThread) [homeassistant.setup] Setup of domain system_log took 0.0 seconds.
2019-03-12 19:10:09 INFO (MainThread) [homeassistant.setup] Setting up onboarding
2019-03-12 19:10:09 INFO (MainThread) [homeassistant.setup] Setting up api
2019-03-12 19:10:09 INFO (MainThread) [homeassistant.setup] Setting up websocket_api
2019-03-12 19:10:09 INFO (MainThread) [homeassistant.setup] Setup of domain websocket_api took 0.0 seconds.
2019-03-12 19:10:09 INFO (MainThread) [homeassistant.setup] Setting up auth
2019-03-12 19:10:09 INFO (MainThread) [homeassistant.setup] Setup of domain auth took 0.0 seconds.
2019-03-12 19:10:09 INFO (MainThread) [homeassistant.setup] Setup of domain api took 0.0 seconds.
2019-03-12 19:10:09 INFO (MainThread) [homeassistant.setup] Setup of domain onboarding took 0.0 seconds.
2019-03-12 19:10:10 INFO (MainThread) [homeassistant.setup] Setting up frontend
2019-03-12 19:10:11 INFO (MainThread) [homeassistant.setup] Setup of domain frontend took 1.7 seconds.
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.setup] Setup of domain recorder took 2.3 seconds.
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.setup] Setting up history
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.setup] Setup of domain history took 0.0 seconds.
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.setup] Setting up discovery
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.setup] Setup of domain discovery took 0.2 seconds.
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.loader] Loaded google from homeassistant.components.google
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.loader] Loaded google.tts from homeassistant.components.google.tts
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.setup] Setting up updater
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.setup] Setup of domain updater took 0.0 seconds.
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.setup] Setting up sun
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.setup] Setup of domain sun took 0.1 seconds.
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.loader] Loaded miflora from custom_components.miflora
2019-03-12 19:10:12 WARNING (MainThread) [homeassistant.loader] You are using a custom component for miflora which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you do experience issues with Home Assistant.
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.loader] Loaded miflora.sensor from custom_components.miflora.sensor
2019-03-12 19:10:12 WARNING (MainThread) [homeassistant.loader] You are using a custom component for miflora.sensor which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you do experience issues with Home Assistant.
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.setup] Setting up sensor
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.setup] Setting up group
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.setup] Setup of domain group took 0.0 seconds.
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.setup] Setting up person
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.setup] Setting up zone
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.setup] Setup of domain zone took 0.0 seconds.
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.setup] Setting up map
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.setup] Setup of domain map took 0.0 seconds.
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 19:10:12 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.setup] Setting up system_health
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.setup] Setup of domain system_health took 0.0 seconds.
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.setup] Setting up device_tracker
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.setup] Setting up cloud
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.setup] Setup of domain cloud took 0.0 seconds.
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.setup] Setting up tts
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.setup] Setting up webhook
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.setup] Setup of domain webhook took 0.0 seconds.
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.setup] Setting up script
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.setup] Setup of domain script took 0.0 seconds.
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.setup] Setting up config
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.setup] Setting up logbook
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.setup] Setup of domain logbook took 0.0 seconds.
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.setup] Setting up conversation
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.setup] Setup of domain conversation took 0.0 seconds.
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.setup] Setting up automation
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.setup] Setup of domain automation took 0.0 seconds.
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.loader] Loaded config.hassbian from homeassistant.components.config.hassbian
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.loader] Loaded config.area_registry from homeassistant.components.config.area_registry
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.loader] Loaded config.script from homeassistant.components.config.script
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.loader] Loaded config.auth_provider_homeassistant from homeassistant.components.config.auth_provider_homeassistant
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.loader] Loaded config.auth from homeassistant.components.config.auth
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.loader] Loaded config.automation from homeassistant.components.config.automation
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.loader] Loaded config.config_entries from homeassistant.components.config.config_entries
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.loader] Loaded config.core from homeassistant.components.config.core
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.loader] Loaded config.customize from homeassistant.components.config.customize
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.loader] Loaded config.device_registry from homeassistant.components.config.device_registry
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.loader] Loaded config.entity_registry from homeassistant.components.config.entity_registry
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.loader] Loaded config.group from homeassistant.components.config.group
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.setup] Setup of domain device_tracker took 0.2 seconds.
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.setup] Setting up mobile_app
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.setup] Setup of domain config took 0.2 seconds.
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.setup] Setup of domain tts took 0.2 seconds.
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.setup] Setup of domain mobile_app took 0.0 seconds.
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.setup] Setup of domain sensor took 0.8 seconds.
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.setup] Setup of domain person took 0.8 seconds.
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.setup] Setting up default_config
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.setup] Setup of domain default_config took 0.0 seconds.
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.bootstrap] Home Assistant initialized in 4.29s
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.core] Starting Home Assistant
2019-03-12 19:10:13 DEBUG (Thread-8) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 19:10:13 DEBUG (Thread-15) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 19:10:13 DEBUG (Thread-19) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 19:10:13 DEBUG (Thread-6) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 19:10:13 DEBUG (Thread-9) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 19:10:13 DEBUG (Thread-7) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 19:10:13 DEBUG (Thread-5) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 19:10:13 INFO (MainThread) [homeassistant.core] Timer:starting
2019-03-12 19:10:13 DEBUG (Thread-3) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 19:10:13 DEBUG (Thread-21) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 19:10:13 DEBUG (Thread-4) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 19:10:13 DEBUG (Thread-20) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 19:10:19 DEBUG (Thread-13) [miflora.miflora_poller] Received result for handle 56: 50 15 32 2E 37 2E 30
2019-03-12 19:10:22 DEBUG (Thread-2) [miflora.miflora_poller] Received result for handle 56: 58 15 32 2E 37 2E 30
2019-03-12 19:10:22 DEBUG (Thread-2) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 19:10:23 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_7_light_intensity is taking over 10 seconds
2019-03-12 19:10:23 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_5_moisture is taking over 10 seconds
2019-03-12 19:10:23 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_14_battery is taking over 10 seconds
2019-03-12 19:10:23 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_2_battery is taking over 10 seconds
2019-03-12 19:10:23 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_8_battery is taking over 10 seconds
2019-03-12 19:10:23 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_15_temperature is taking over 10 seconds
2019-03-12 19:10:23 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_20_light_intensity is taking over 10 seconds
2019-03-12 19:10:23 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_16_battery is taking over 10 seconds
2019-03-12 19:10:23 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_6_moisture is taking over 10 seconds
2019-03-12 19:10:23 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_17_temperature is taking over 10 seconds
2019-03-12 19:10:23 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_4_light_intensity is taking over 10 seconds
2019-03-12 19:10:23 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_18_battery is taking over 10 seconds
2019-03-12 19:10:23 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_3_battery is taking over 10 seconds
2019-03-12 19:10:23 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_19_temperature is taking over 10 seconds
2019-03-12 19:10:23 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_9_light_intensity is taking over 10 seconds
2019-03-12 19:10:23 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_1_temperature is taking over 10 seconds
2019-03-12 19:10:23 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_10_light_intensity is taking over 10 seconds
2019-03-12 19:10:23 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_11_battery is taking over 10 seconds
2019-03-12 19:10:27 DEBUG (Thread-16) [miflora.miflora_poller] Received result for handle 56: 64 15 32 2E 37 2E 30
2019-03-12 19:10:27 DEBUG (Thread-16) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 19:10:29 DEBUG (Thread-17) [miflora.miflora_poller] Received result for handle 56: 5B 15 32 2E 37 2E 30
2019-03-12 19:10:29 DEBUG (Thread-17) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 19:10:29 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_12_light_intensity is taking over 10 seconds
2019-03-12 19:10:30 DEBUG (Thread-10) [miflora.miflora_poller] Received result for handle 56: 58 15 32 2E 37 2E 30
2019-03-12 19:10:30 DEBUG (Thread-10) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 19:10:32 DEBUG (Thread-13) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 19:10:32 INFO (MainThread) [homeassistant.components.discovery] Unknown service discovered: dlna_dmr {'model_name': 'MediaRenderer', 'model_number': '100', 'port': 52323, 'ssdp_description': 'http://10.0.0.131:52323/MediaRenderer.xml', 'udn': 'uuid:23456789-1234-1010-8000-cc988b6db375', 'manufacturer': 'Sony Corporation', 'host': '10.0.0.131', 'name': 'KD-65XF9005', 'serial': None, 'upnp_device_type': 'urn:schemas-upnp-org:device:MediaRenderer:1'}
2019-03-12 19:10:32 INFO (MainThread) [homeassistant.components.discovery] Unknown service discovered: dlna_dmr {'model_name': 'Marantz SR7013', 'model_number': 'Aios 4.025', 'port': 60006, 'ssdp_description': 'http://10.0.0.148:60006/upnp/desc/aios_device/aios_device.xml', 'udn': 'uuid:c34d81e9-7437-1dc5-0080-0005cdc485e6', 'manufacturer': 'Marantz', 'host': '10.0.0.148', 'name': 'Marantz SR7013', 'serial': 'BCS27180800161', 'upnp_device_type': 'urn:schemas-denon-com:device:AiosDevice:1'}
2019-03-12 19:10:32 INFO (MainThread) [homeassistant.components.discovery] Unknown service discovered: dlna_dms {'model_name': 'Marantz SR7013', 'model_number': 'Aios 4.025', 'port': 60006, 'ssdp_description': 'http://10.0.0.148:60006/upnp/desc/aios_device/aios_device.xml', 'udn': 'uuid:c34d81e9-7437-1dc5-0080-0005cdc485e6', 'manufacturer': 'Marantz', 'host': '10.0.0.148', 'name': 'Marantz SR7013', 'serial': 'BCS27180800161', 'upnp_device_type': 'urn:schemas-denon-com:device:AiosDevice:1'}
2019-03-12 19:10:32 INFO (MainThread) [homeassistant.loader] Loaded cast from homeassistant.components.cast
2019-03-12 19:10:32 INFO (MainThread) [homeassistant.loader] Loaded persistent_notification from homeassistant.components.persistent_notification
2019-03-12 19:10:32 INFO (MainThread) [homeassistant.components.discovery] Unknown service discovered: spotify_connect {'hostname': 'Marantz-SR7013.local.', 'host': '10.0.0.148', 'port': 80, 'properties': {'VERSION': '1.0', 'CPath': '/spotify'}}
2019-03-12 19:10:32 INFO (MainThread) [homeassistant.components.discovery] Unknown service discovered: homekit {'hostname': 'tadoBridgeIB1393427968.local.', 'host': '10.0.0.40', 'name': 'tado Internet Bridge IB1393427968', 'port': 3000, 'properties': {'md': 'tado Internet Bridge', 'id': '23:03:45:4e:a3:42', 'pv': '1.0', 'ff': '1', 's#': '1', 'sf': '0', 'ci': '2', 'c#': '22'}}
2019-03-12 19:10:32 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_13_light_intensity is taking over 10 seconds
2019-03-12 19:10:32 DEBUG (Thread-12) [miflora.miflora_poller] Received result for handle 56: 56 15 32 2E 37 2E 30
2019-03-12 19:10:32 DEBUG (Thread-12) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 19:10:37 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_14_light_intensity is taking over 10 seconds
2019-03-12 19:10:39 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_2_light_intensity is taking over 10 seconds
2019-03-12 19:10:40 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_8_light_intensity is taking over 10 seconds
2019-03-12 19:10:42 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_16_temperature is taking over 10 seconds
2019-03-12 19:10:47 DEBUG (Thread-14) [miflora.miflora_poller] Received result for handle 56: 62 15 32 2E 37 2E 30
2019-03-12 19:10:47 DEBUG (Thread-14) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 19:10:52 DEBUG (Thread-18) [miflora.miflora_poller] Received result for handle 56: 64 15 32 2E 37 2E 30
2019-03-12 19:10:52 DEBUG (Thread-18) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 19:10:57 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_18_light_intensity is taking over 10 seconds
2019-03-12 19:10:58 DEBUG (Thread-8) [miflora.miflora_poller] Received result for handle 56: 64 15 32 2E 37 2E 30
2019-03-12 19:11:02 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_3_light_intensity is taking over 10 seconds
2019-03-12 19:11:02 DEBUG (Thread-15) [miflora.miflora_poller] Received result for handle 56: 5B 15 32 2E 37 2E 30
2019-03-12 19:11:07 DEBUG (Thread-11) [miflora.miflora_poller] Received result for handle 56: 56 15 32 2E 37 2E 30
2019-03-12 19:11:07 DEBUG (Thread-11) [miflora.miflora_poller] Filling cache with new sensor data.
2019-03-12 19:11:09 DEBUG (Thread-19) [miflora.miflora_poller] Received result for handle 56: 64 15 32 2E 37 2E 30
2019-03-12 19:11:13 DEBUG (Thread-6) [miflora.miflora_poller] Received result for handle 56: 5D 15 32 2E 37 2E 30
2019-03-12 19:11:16 DEBUG (Thread-9) [miflora.miflora_poller] Received result for handle 56: 59 15 32 2E 37 2E 30
2019-03-12 19:11:17 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.plant_11_light_intensity is taking over 10 seconds
2019-03-12 19:11:18 DEBUG (Thread-7) [miflora.miflora_poller] Received result for handle 56: 50 15 32 2E 37 2E 30
2019-03-12 19:11:45 DEBUG (Thread-5) [miflora.miflora_poller] Received result for handle 56: 64 15 32 2E 37 2E 30
2019-03-12 19:11:47 DEBUG (Thread-3) [miflora.miflora_poller] Received result for handle 56: 64 15 32 2E 37 2E 30
2019-03-12 19:11:51 DEBUG (Thread-21) [miflora.miflora_poller] Received result for handle 56: 53 15 32 2E 37 2E 30
2019-03-12 19:11:55 DEBUG (Thread-4) [miflora.miflora_poller] Received result for handle 56: 3A 15 32 2E 37 2E 30
2019-03-12 19:11:59 DEBUG (Thread-20) [miflora.miflora_poller] Received result for handle 56: 53 15 32 2E 37 2E 30
2019-03-12 19:12:01 DEBUG (Thread-2) [miflora.miflora_poller] Received result for handle 53: B8 00 00 66 00 00 00 0F 0D 00 02 3C 00 FB 34 9B
2019-03-12 19:12:01 DEBUG (Thread-2) [miflora.miflora_poller] Using cache (0:00:00.097283 < 0:20:11)
2019-03-12 19:12:01 DEBUG (Thread-2) [miflora.miflora_poller] Using cache (0:00:00.109487 < 0:20:11)
2019-03-12 19:12:01 INFO (MainThread) [homeassistant.components.http.view] Serving /api/websocket to 10.0.0.145 (auth: False)
2019-03-12 19:12:02 INFO (MainThread) [homeassistant.components.http.view] Serving /auth/token to 10.0.0.145 (auth: False)
2019-03-12 19:12:02 INFO (MainThread) [homeassistant.loader] Loaded deconz from homeassistant.components.deconz

I don't know if this helps at all :)

awarecan commented 5 years ago

Both of your logs indicated the http component is running

2019-03-12 19:12:01 INFO (MainThread) [homeassistant.components.http.view] Serving /api/websocket to 10.0.0.145 (auth: False)

What you got from browser console?

awarecan commented 5 years ago

I also didn't found homeassistant.components.sensor.miflora logs, is there a typo in . your config logger section?

ACrazyConcept commented 5 years ago

Both of your logs indicated the http component is running

2019-03-12 19:12:01 INFO (MainThread) [homeassistant.components.http.view] Serving /api/websocket to 10.0.0.145 (auth: False)

What you got from browser console?

This is the browser console when the front end does no allow me to connect:

app-38927061.js:557 Fetch finished loading: GET "http://10.0.0.129:8123/static/translations/en-b61a14cc03cdb9d0fb9165ee574419e3.json".
getTranslation @ app-38927061.js:557
(anonymous) @ app-38927061.js:557
__webpack_require__ @ app-38927061.js:1
(anonymous) @ app-38927061.js:1147
__webpack_require__ @ app-38927061.js:1
(anonymous) @ app-38927061.js:1
(anonymous) @ app-38927061.js:1
core-2f337d0e.js:1 POST http://10.0.0.129:8123/auth/token net::ERR_EMPTY_RESPONSE
(anonymous) @ core-2f337d0e.js:1
(anonymous) @ core-2f337d0e.js:1
(anonymous) @ core-2f337d0e.js:1
(anonymous) @ core-2f337d0e.js:1
e @ core-2f337d0e.js:1
l @ core-2f337d0e.js:1
(anonymous) @ core-2f337d0e.js:1
(anonymous) @ core-2f337d0e.js:1
(anonymous) @ core-2f337d0e.js:1
(anonymous) @ core-2f337d0e.js:1
e @ core-2f337d0e.js:1
n.refreshAccessToken @ core-2f337d0e.js:1
(anonymous) @ core-2f337d0e.js:1
(anonymous) @ core-2f337d0e.js:1
(anonymous) @ core-2f337d0e.js:1
(anonymous) @ core-2f337d0e.js:1
e @ core-2f337d0e.js:1
b @ core-2f337d0e.js:1
core-2f337d0e.js:1 Fetch failed loading: POST "http://10.0.0.129:8123/auth/token".
(anonymous) @ core-2f337d0e.js:1
(anonymous) @ core-2f337d0e.js:1
(anonymous) @ core-2f337d0e.js:1
(anonymous) @ core-2f337d0e.js:1
e @ core-2f337d0e.js:1
l @ core-2f337d0e.js:1
(anonymous) @ core-2f337d0e.js:1
(anonymous) @ core-2f337d0e.js:1
(anonymous) @ core-2f337d0e.js:1
(anonymous) @ core-2f337d0e.js:1
e @ core-2f337d0e.js:1
n.refreshAccessToken @ core-2f337d0e.js:1
(anonymous) @ core-2f337d0e.js:1
(anonymous) @ core-2f337d0e.js:1
(anonymous) @ core-2f337d0e.js:1
(anonymous) @ core-2f337d0e.js:1
e @ core-2f337d0e.js:1
b @ core-2f337d0e.js:1
states:1 Uncaught (in promise) 1
Promise.then (async)
151 @ core-2f337d0e.js:1
__webpack_require__ @ core-2f337d0e.js:1
(anonymous) @ core-2f337d0e.js:1
(anonymous) @ core-2f337d0e.js:1
ACrazyConcept commented 5 years ago

I also didn't found homeassistant.components.sensor.miflora logs, is there a typo in . your config logger section?

Yes I also noticed. This is my config:

logger:
  default: info
  logs:
    homeassistant.components.sensor.miflora: debug
    miflora: debug