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
73.9k stars 30.97k forks source link

0.101.2 does not boot (timeout) #28555

Closed tiagofreire-pt closed 5 years ago

tiagofreire-pt commented 5 years ago

Home Assistant release with the issue: 0.101.2, fully upgraded OVA build on Proxmox VE 6.

Last working Home Assistant release (if known): 0.100

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

Integration: N/A

Description of problem: The boot timeout is achieved with no error messages.

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

Traceback (if applicable): Console:

WARNING (MainThread) [hassio.homeassistant] Don't wait anymore of Home Assistant startup!

SSH add-on:

~# hassio homeassistant restart
Processing... Done.

Error: Unknown Error, see logs

Additional information: The command ha logs does result blank.

The configuration is valid with no errors.

frenck commented 5 years ago

Check the homeassistant.log file manually for hints. Unfortunately, this report contains nothing that can point in a direction.

tiagofreire-pt commented 5 years ago

Check the homeassistant.log file manually for hints. Unfortunately, this report contains nothing that can point in a direction.

core-ssh:~# hassio ha logs
2019-11-05 20:39:07 ERROR (MainThread) [homeassistant.components.pi_hole] Unable to fetch data from Pi-hole
Config directory: /config
/config/www/community/zigbee2mqtt_networkmap
2019-11-05 20:39:14 ERROR (MainThread) [custom_components.asyncpioneer.media_player] No connection to 192.168.10.31:8102, retry in 30s
2019-11-05 20:51:53 INFO (SyncWorker_3) [homeassistant.loader] Loaded alexa_media from custom_components.alexa_media
2019-11-05 20:51:53 INFO (SyncWorker_8) [homeassistant.loader] Loaded deepstack_face from custom_components.deepstack_face
2019-11-05 20:51:53 INFO (SyncWorker_2) [homeassistant.loader] Loaded authenticated from custom_components.authenticated
2019-11-05 20:51:53 INFO (SyncWorker_14) [homeassistant.loader] Loaded ssh from custom_components.ssh
2019-11-05 20:51:53 INFO (SyncWorker_13) [homeassistant.loader] Loaded zigbee2mqtt_networkmap from custom_components.zigbee2mqtt_networkmap
2019-11-05 20:51:53 INFO (SyncWorker_15) [homeassistant.loader] Loaded hacs from custom_components.hacs
2019-11-05 20:51:53 INFO (SyncWorker_3) [homeassistant.loader] Loaded shell_command from homeassistant.components.shell_command
2019-11-05 20:51:53 INFO (SyncWorker_17) [homeassistant.loader] Loaded transmission from homeassistant.components.transmission
2019-11-05 20:51:53 WARNING (MainThread) [homeassistant.loader] You are using a custom integration for zigbee2mqtt_networkmap 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-11-05 20:51:53 INFO (SyncWorker_11) [homeassistant.loader] Loaded fan from homeassistant.components.fan
2019-11-05 20:51:53 INFO (SyncWorker_5) [homeassistant.loader] Loaded image_processing from homeassistant.components.image_processing
2019-11-05 20:51:53 INFO (SyncWorker_8) [homeassistant.loader] Loaded http from homeassistant.components.http
2019-11-05 20:51:53 INFO (SyncWorker_14) [homeassistant.loader] Loaded influxdb from homeassistant.components.influxdb
2019-11-05 20:51:53 INFO (SyncWorker_18) [homeassistant.loader] Loaded asuswrt from homeassistant.components.asuswrt
2019-11-05 20:51:53 INFO (SyncWorker_2) [homeassistant.loader] Loaded speedtestdotnet from homeassistant.components.speedtestdotnet
2019-11-05 20:51:53 INFO (SyncWorker_3) [homeassistant.loader] Loaded discovery from homeassistant.components.discovery
2019-11-05 20:51:53 INFO (SyncWorker_10) [homeassistant.loader] Loaded recorder from homeassistant.components.recorder
2019-11-05 20:51:53 INFO (SyncWorker_12) [homeassistant.loader] Loaded zone from homeassistant.components.zone
2019-11-05 20:51:53 WARNING (MainThread) [homeassistant.loader] You are using a custom integration for alexa_media 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-11-05 20:51:53 INFO (SyncWorker_5) [homeassistant.loader] Loaded light from homeassistant.components.light
2019-11-05 20:51:53 INFO (SyncWorker_4) [homeassistant.loader] Loaded binary_sensor from homeassistant.components.binary_sensor
2019-11-05 20:51:53 INFO (SyncWorker_0) [homeassistant.loader] Loaded group from homeassistant.components.group
2019-11-05 20:51:53 WARNING (MainThread) [homeassistant.loader] You are using a custom integration for hacs 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-11-05 20:51:53 INFO (SyncWorker_4) [homeassistant.loader] Loaded input_boolean from homeassistant.components.input_boolean
2019-11-05 20:51:53 INFO (SyncWorker_5) [homeassistant.loader] Loaded default_config from homeassistant.components.default_config
2019-11-05 20:51:53 INFO (SyncWorker_15) [homeassistant.loader] Loaded sensor from homeassistant.components.sensor
2019-11-05 20:51:53 INFO (SyncWorker_16) [homeassistant.loader] Loaded pi_hole from homeassistant.components.pi_hole
2019-11-05 20:51:53 INFO (SyncWorker_7) [homeassistant.loader] Loaded owntracks from homeassistant.components.owntracks
2019-11-05 20:51:53 INFO (SyncWorker_0) [homeassistant.loader] Loaded telegram_bot from homeassistant.components.telegram_bot
2019-11-05 20:51:53 INFO (SyncWorker_1) [homeassistant.loader] Loaded notify from homeassistant.components.notify
2019-11-05 20:51:53 INFO (SyncWorker_15) [homeassistant.loader] Loaded mqtt from homeassistant.components.mqtt
2019-11-05 20:51:53 INFO (SyncWorker_19) [homeassistant.loader] Loaded twilio from homeassistant.components.twilio
2019-11-05 20:51:53 INFO (SyncWorker_4) [homeassistant.loader] Loaded esphome from homeassistant.components.esphome
2019-11-05 20:51:53 INFO (SyncWorker_6) [homeassistant.loader] Loaded frontend from homeassistant.components.frontend
2019-11-05 20:51:53 INFO (SyncWorker_5) [homeassistant.loader] Loaded webhook from homeassistant.components.webhook
2019-11-05 20:51:53 INFO (SyncWorker_16) [homeassistant.loader] Loaded emulated_hue from homeassistant.components.emulated_hue
2019-11-05 20:51:53 INFO (SyncWorker_17) [homeassistant.loader] Loaded ffmpeg from homeassistant.components.ffmpeg
2019-11-05 20:51:53 INFO (SyncWorker_18) [homeassistant.loader] Loaded camera from homeassistant.components.camera
2019-11-05 20:51:53 WARNING (MainThread) [homeassistant.loader] You are using a custom integration for proxmox 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-11-05 20:51:53 INFO (SyncWorker_7) [homeassistant.loader] Loaded input_select from homeassistant.components.input_select
2019-11-05 20:51:53 INFO (SyncWorker_9) [homeassistant.loader] Loaded automation from homeassistant.components.automation
2019-11-05 20:51:53 INFO (MainThread) [homeassistant.loader] Loaded proxmox from custom_components.proxmox
2019-11-05 20:51:53 INFO (SyncWorker_3) [homeassistant.loader] Loaded vacuum from homeassistant.components.vacuum
2019-11-05 20:51:53 INFO (SyncWorker_11) [homeassistant.loader] Loaded script from homeassistant.components.script
2019-11-05 20:51:53 INFO (SyncWorker_8) [homeassistant.loader] Loaded logger from homeassistant.components.logger
2019-11-05 20:51:53 INFO (SyncWorker_12) [homeassistant.loader] Loaded timer from homeassistant.components.timer
2019-11-05 20:51:53 INFO (SyncWorker_10) [homeassistant.loader] Loaded hassio from homeassistant.components.hassio
2019-11-05 20:51:53 INFO (SyncWorker_13) [homeassistant.loader] Loaded device_tracker from homeassistant.components.device_tracker
2019-11-05 20:51:53 INFO (SyncWorker_1) [homeassistant.loader] Loaded websocket_api from homeassistant.components.websocket_api
2019-11-05 20:51:53 INFO (SyncWorker_15) [homeassistant.loader] Loaded media_player from homeassistant.components.media_player
2019-11-05 20:51:53 INFO (SyncWorker_0) [homeassistant.loader] Loaded plant from homeassistant.components.plant
2019-11-05 20:51:53 INFO (SyncWorker_19) [homeassistant.loader] Loaded ipma from homeassistant.components.ipma
2019-11-05 20:51:53 INFO (SyncWorker_16) [homeassistant.loader] Loaded persistent_notification from homeassistant.components.persistent_notification
2019-11-05 20:51:53 INFO (SyncWorker_14) [homeassistant.loader] Loaded panel_iframe from homeassistant.components.panel_iframe
2019-11-05 20:51:53 INFO (SyncWorker_2) [homeassistant.loader] Loaded input_number from homeassistant.components.input_number
2019-11-05 20:51:53 INFO (SyncWorker_5) [homeassistant.loader] Loaded homeassistant from homeassistant.components.homeassistant
2019-11-05 20:51:53 INFO (SyncWorker_6) [homeassistant.loader] Loaded zoneminder from homeassistant.components.zoneminder
2019-11-05 20:51:53 INFO (SyncWorker_4) [homeassistant.loader] Loaded switch from homeassistant.components.switch
2019-11-05 20:51:53 WARNING (MainThread) [homeassistant.loader] You are using a custom integration for electricity 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-11-05 20:51:53 INFO (MainThread) [homeassistant.loader] Loaded electricity from custom_components.electricity
2019-11-05 20:51:53 INFO (SyncWorker_17) [homeassistant.loader] Loaded api from homeassistant.components.api
2019-11-05 20:51:53 INFO (SyncWorker_9) [homeassistant.loader] Loaded panel_custom from homeassistant.components.panel_custom
2019-11-05 20:51:53 INFO (SyncWorker_18) [homeassistant.loader] Loaded device_automation from homeassistant.components.device_automation
2019-11-05 20:51:53 INFO (MainThread) [homeassistant.setup] Setting up persistent_notification
2019-11-05 20:51:53 INFO (MainThread) [homeassistant.setup] Setup of domain persistent_notification took 0.0 seconds.
2019-11-05 20:51:53 INFO (MainThread) [homeassistant.setup] Setting up homeassistant
2019-11-05 20:51:53 INFO (MainThread) [homeassistant.setup] Setup of domain homeassistant took 0.0 seconds.
2019-11-05 20:51:53 INFO (SyncWorker_3) [homeassistant.loader] Loaded auth from homeassistant.components.auth
2019-11-05 20:51:53 INFO (SyncWorker_12) [homeassistant.loader] Loaded lovelace from homeassistant.components.lovelace
2019-11-05 20:51:53 INFO (SyncWorker_11) [homeassistant.loader] Loaded cloud from homeassistant.components.cloud
2019-11-05 20:51:53 INFO (SyncWorker_1) [homeassistant.loader] Loaded config from homeassistant.components.config
2019-11-05 20:51:53 INFO (SyncWorker_13) [homeassistant.loader] Loaded onboarding from homeassistant.components.onboarding
2019-11-05 20:51:53 INFO (SyncWorker_0) [homeassistant.loader] Loaded system_log from homeassistant.components.system_log
2019-11-05 20:51:53 INFO (SyncWorker_14) [homeassistant.loader] Loaded history from homeassistant.components.history
2019-11-05 20:51:53 INFO (SyncWorker_2) [homeassistant.loader] Loaded logbook from homeassistant.components.logbook
2019-11-05 20:51:53 INFO (SyncWorker_5) [homeassistant.loader] Loaded map from homeassistant.components.map
2019-11-05 20:51:53 INFO (SyncWorker_7) [homeassistant.loader] Loaded mobile_app from homeassistant.components.mobile_app
2019-11-05 20:51:53 INFO (SyncWorker_18) [homeassistant.loader] Loaded person from homeassistant.components.person
2019-11-05 20:51:53 INFO (SyncWorker_8) [homeassistant.loader] Loaded ssdp from homeassistant.components.ssdp
2019-11-05 20:51:53 INFO (SyncWorker_10) [homeassistant.loader] Loaded sun from homeassistant.components.sun
2019-11-05 20:51:53 INFO (SyncWorker_12) [homeassistant.loader] Loaded system_health from homeassistant.components.system_health
2019-11-05 20:51:53 INFO (SyncWorker_1) [homeassistant.loader] Loaded updater from homeassistant.components.updater
2019-11-05 20:51:53 INFO (SyncWorker_19) [homeassistant.loader] Loaded zeroconf from homeassistant.components.zeroconf
2019-11-05 20:51:53 INFO (MainThread) [homeassistant.bootstrap] Setting up {'system_log', 'logger'}
2019-11-05 20:51:53 INFO (MainThread) [homeassistant.setup] Setting up logger
/usr/local/lib/python3.7/site-packages/asyncssh/crypto/ec.py:176: CryptographyDeprecationWarning: encode_point has been deprecated on EllipticCurvePublicNumbers and will be removed in a future version. Please use EllipticCurvePublicKey.public_bytes to obtain both compressed and uncompressed point encoding.
  return pub.encode_point()
/usr/local/lib/python3.7/site-packages/asyncssh/crypto/ec.py:182: CryptographyDeprecationWarning: Support for unsafe construction of public numbers from encoded data will be removed in a future version. Please use EllipticCurvePublicKey.from_encoded_point
  self._priv_key.curve, peer_public).public_key(backend)
/usr/local/lib/python3.7/site-packages/asyncssh/crypto/ec.py:144: CryptographyDeprecationWarning: Support for unsafe construction of public numbers from encoded data will be removed in a future version. Please use EllipticCurvePublicKey.from_encoded_point
  public_value)
2019-11-05 20:52:06 ERROR (MainThread) [hole] Can not load data from *hole: 192.168.10.72
2019-11-05 20:52:06 ERROR (MainThread) [homeassistant.components.pi_hole] Unable to fetch data from Pi-hole
Config directory: /config
/config/www/community/zigbee2mqtt_networkmap
2019-11-05 20:52:13 ERROR (MainThread) [custom_components.asyncpioneer.media_player] No connection to 192.168.10.31:8102, retry in 30s
tiagofreire-pt commented 5 years ago

If I stop or start the home assistant from the console, the supervisor logs reports:

19-11-05 21:11:10 ERROR (MainThread) [hassio.utils] Can't execute stop while a task is in progress
19-11-05 21:11:16 ERROR (MainThread) [hassio.utils] Can't execute stop while a task is in progress
19-11-05 21:11:23 ERROR (MainThread) [hassio.utils] Can't execute start while a task is in progress
19-11-05 21:11:26 ERROR (MainThread) [hassio.utils] Can't execute stop while a task is in progress
tiagofreire-pt commented 5 years ago

@frenck I managed to temporarily regain control over HA, after waiting several hours, then stopped and started manually the HA through CLI, with no errors. But it took ages to boot even so, almost smashing the 10 minutes timeout defined on Hassos. On 0.100 and previous versions, the reboot time with Hassos included was around 3 minutes in total.

This is a huge pain, I'm frequently rebooting HA as result of upgrading components and even itself.

manuelbarbosa commented 5 years ago

Please check my solution for Hass.io on:

https://github.com/home-assistant/home-assistant/issues/28561#issuecomment-550249927

The same solution applies for this not booting issues.

frenck commented 5 years ago

Be sure to check out the release notes. api_password & trusted_networks have been removed, which seems to be the culprit on upgrading for a lot of people.

tiagofreire-pt commented 5 years ago

Be sure to check out the release notes. api_password & trusted_networks have been removed, which seems to be the culprit on upgrading for a lot of people.

I don't have any of them. Although, my configuration is validated on 0.101.2 it's not clear why this version of HA lags on booting, smashing the internal timeout without any further warning or error message.

Is there any way to downgrade for a previous version? Even through a clean install?

tiagofreire-pt commented 5 years ago

@frenck, HA logs with "info" level.

# hassio ha logs
2019-11-14 12:10:37 INFO (MainThread) [homeassistant.setup] Setup of domain alarm_control_panel took 0.0 seconds.
2019-11-14 12:10:37 INFO (MainThread) [homeassistant.components.notify] Setting up notify.alexa_media
2019-11-14 12:10:37 INFO (MainThread) [homeassistant.components.media_player] Setting up media_player.alexa_media
2019-11-14 12:10:37 INFO (MainThread) [homeassistant.components.switch] Setting up switch.alexa_media
2019-11-14 12:10:37 INFO (MainThread) [homeassistant.components.alarm_control_panel] Setting up alarm_control_panel.alexa_media
2019-11-14 12:10:40 INFO (MainThread) [hacs.data] Restore started
2019-11-14 12:10:40 INFO (MainThread) [hacs.data] Restore done
2019-11-14 12:10:40 INFO (MainThread) [homeassistant.setup] Setup of domain hacs took 19.8 seconds.
2019-11-14 12:10:41 INFO (SyncWorker_2) [homeassistant.components.nmap_tracker.device_tracker] No MAC address found for 172.24.1.107
2019-11-14 12:10:41 INFO (MainThread) [homeassistant.components.esphome] Successfully connected to sonoff_corredor.local
2019-11-14 12:10:41 INFO (MainThread) [homeassistant.components.esphome] Successfully connected to sonoff_cristaleira_movel_multimedia.local
2019-11-14 12:10:41 INFO (SyncWorker_13) [homeassistant.loader] Loaded miflora from homeassistant.components.miflora
2019-11-14 12:10:42 INFO (MainThread) [homeassistant.components.esphome] Successfully connected to sonoff_roborock.local
2019-11-14 12:10:42 INFO (MainThread) [homeassistant.components.esphome] Successfully connected to sonoff_cabeceira_liliana.local
2019-11-14 12:10:42 INFO (SyncWorker_2) [homeassistant.components.nmap_tracker.device_tracker] No MAC address found for 172.24.1.97
2019-11-14 12:10:42 INFO (MainThread) [homeassistant.setup] Setup of domain notify took 17.5 seconds.
2019-11-14 12:10:43 INFO (SyncWorker_19) [homeassistant.loader] Loaded scrape from homeassistant.components.scrape
2019-11-14 12:10:43 INFO (MainThread) [homeassistant.components.notify] Setting up notify.mobile_app
2019-11-14 12:10:43 INFO (MainThread) [homeassistant.components.notify] Setting up notify.alexa_media
2019-11-14 12:10:44 INFO (SyncWorker_2) [homeassistant.components.nmap_tracker.device_tracker] No MAC address found for 172.24.1.110
2019-11-14 12:10:45 INFO (SyncWorker_8) [homeassistant.loader] Loaded rest from homeassistant.components.rest
2019-11-14 12:10:45 INFO (SyncWorker_9) [homeassistant.loader] Loaded season from homeassistant.components.season
2019-11-14 12:10:46 INFO (MainThread) [homeassistant.components.esphome] Successfully connected to sonoff_cabeceira_tiago.local
2019-11-14 12:10:46 INFO (SyncWorker_2) [homeassistant.loader] Loaded here_travel_time from homeassistant.components.here_travel_time
2019-11-14 12:10:46 INFO (MainThread) [homeassistant.setup] Setting up sensor
2019-11-14 12:10:46 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt
2019-11-14 12:10:46 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-11-14 12:10:46 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt
2019-11-14 12:10:46 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.rest
2019-11-14 12:10:46 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-11-14 12:10:46 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt
2019-11-14 12:10:46 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.scrape
2019-11-14 12:10:46 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt
2019-11-14 12:10:46 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.rest
2019-11-14 12:10:46 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.command_line
2019-11-14 12:10:46 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt
2019-11-14 12:10:46 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.scrape
2019-11-14 12:10:46 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt
2019-11-14 12:10:46 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.rest
2019-11-14 12:10:46 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.command_line
2019-11-14 12:10:46 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt
2019-11-14 12:10:46 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.scrape
2019-11-14 12:10:46 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt
2019-11-14 12:10:46 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.rest
2019-11-14 12:10:46 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.command_line
2019-11-14 12:10:46 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt
2019-11-14 12:10:46 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.scrape
2019-11-14 12:10:46 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.broadlink
2019-11-14 12:10:46 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.scrape
2019-11-14 12:10:46 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.here_travel_time
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.ssh
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.time_date
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.scrape
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.season
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.here_travel_time
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.scrape
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.zoneminder
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.scrape
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.scrape
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.scrape
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.scrape
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.miflora
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.rest
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.scrape
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.scrape
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-11-14 12:10:47 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.rest
2019-11-14 12:10:48 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.systemmonitor
2019-11-14 12:10:48 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt
2019-11-14 12:10:48 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.authenticated
2019-11-14 12:10:48 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt
2019-11-14 12:10:48 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt
2019-11-14 12:10:48 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.rest
2019-11-14 12:10:48 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.template
2019-11-14 12:10:48 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.yr
2019-11-14 12:10:48 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mqtt

The problem remains even on version 0.101.3.

tiagofreire-pt commented 5 years ago

The problem was caused by this custom component: https://github.com/custom-components/sensor.ssh

  - platform: ssh
    host: !secret proxmox_host
    name: 'NUC CPU Temp'
    username: !secret proxmox_user
    password: !secret proxmox_pass
    command: "sensors | grep 'Package id 0:' | cut -c17-20"
    value_template: >-
      {%- set line = value.split("\r\n") -%}
      {{ line[1] }}
    unit_of_measurement: '°C'
springstan commented 5 years ago

@tiagofreire-pt If this issue was caused by a custom component please close this issue and open a new one there :)

tiagofreire-pt commented 5 years ago

@frenck, there is any watchdog for these kind of situations, on custom components lagging the whole HA instance?

springstan commented 5 years ago

I do not think so due to the fact that the following warning is added to home-assistant.log for every custom component you add. WARNING (MainThread) [homeassistant.loader] You are using a custom integration for {custom integration name} 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.

tiagofreire-pt commented 5 years ago

@springstan, I see. That warning is quite useful on alerting for that purpose.

Most of users, like me, have an enormous number of custom components on HA, giving an huge pain debugging these kind of situations that are not that clear, as stated above on the system logs.

Would be admissible investing some time on a higher level "poke-yoke" that should mitigate this kind of situations?

springstan commented 5 years ago

I understand that this can be quite difficult for users to handle, but I am in no position to answer your last question.

frenck commented 5 years ago

We have 0 influence on code of custom integrations. They can (and it this case has) break your system in many ways.

Simply because there isn't any code review from the HA team.

Nevertheless, I'm glad you found and resolved your issue!