Hassio stops after few minutes from start. Nothing in log

Installation Home Assistant OS
1

How to troubleshoot?

Put some commands in logger?

2017-10-06 07:37:13 WARNING (Recorder) [homeassistant.components.recorder] Ended unfinished session (id=17 from 2017-10-06 05:32:19.906527)
2017-10-06 07:37:19 WARNING (SyncWorker_3) [homeassistant.components.sensor.file] File or data not present at the moment: upslog.txt
2017-10-06 07:37:25 WARNING (MainThread) [homeassistant.setup] Setup of climate is taking over 10 seconds.
2017-10-06 07:37:25 WARNING (MainThread) [homeassistant.setup] Setup of binary_sensor is taking over 10 seconds.
2017-10-06 07:37:26 WARNING (SyncWorker_14) [custom_components.emulated_hue] Alexa type is deprecated and will be removed in a future version
2017-10-06 07:37:26 WARNING (SyncWorker_14) [custom_components.emulated_hue] Listen IP address not specified, auto-detected address is 192.168.1.238
2017-10-06 07:37:26 WARNING (SyncWorker_14) [custom_components.emulated_hue] Alexa type is deprecated and will be removed in a future version
2017-10-06 07:37:26 WARNING (SyncWorker_14) [custom_components.emulated_hue] Listen IP address not specified, auto-detected address is 192.168.1.238
2017-10-06 07:37:26 WARNING (SyncWorker_14) [custom_components.emulated_hue] Listen IP address not specified, auto-detected address is 192.168.1.238
2017-10-06 07:37:27 WARNING (MainThread) [homeassistant.setup] Setup of sensor is taking over 10 seconds.
2017-10-06 07:37:27 WARNING (MainThread) [homeassistant.setup] Setup of camera is taking over 10 seconds.
2017-10-06 07:37:28 WARNING (MainThread) [homeassistant.setup] Setup of media_player is taking over 10 seconds.
2017-10-06 07:37:31 WARNING (MainThread) [homeassistant.setup] Setup of device_tracker is taking over 10 seconds.
2017-10-06 07:37:31 WARNING (MainThread) [homeassistant.setup] Setup of switch is taking over 10 seconds.
2017-10-06 07:37:31 WARNING (MainThread) [homeassistant.setup] Setup of light is taking over 10 seconds.
2017-10-06 07:37:38 WARNING (MainThread) [homeassistant.setup] Setup of tts is taking over 10 seconds.
2017-10-06 07:37:40 ERROR (SyncWorker_16) [homeassistant.components.sensor.netatmo] Module name: "Gilma_outdoor" not found
2017-10-06 07:37:47 ERROR (SyncWorker_12) [homeassistant.components.sensor.nut] NUT Sensor has no data, unable to setup
2017-10-06 07:38:21 ERROR (MainThread) [homeassistant.components.switch] Error while setting up platform broadlink
Traceback (most recent call last):
  File "/usr/lib/python3.6/site-packages/homeassistant/helpers/entity_component.py", line 164, in _async_setup_platform
    SLOW_SETUP_MAX_WAIT, loop=self.hass.loop)
  File "/usr/lib/python3.6/asyncio/tasks.py", line 352, in wait_for
    return fut.result()
  File "/usr/lib/python3.6/asyncio/futures.py", line 244, in result
    raise self._exception
  File "/usr/lib/python3.6/concurrent/futures/thread.py", line 55, in run
    result = self.fn(*self.args, **self.kwargs)
  File "/usr/lib/python3.6/site-packages/homeassistant/components/switch/broadlink.py", line 150, in setup_platform
    device_config.get(CONF_COMMAND_OFF)
  File "/usr/lib/python3.6/site-packages/homeassistant/components/switch/broadlink.py", line 186, in __init__
    self._command_off = b64decode(command_off) if command_off else None
  File "/usr/lib/python3.6/base64.py", line 87, in b64decode
    return binascii.a2b_base64(s)
binascii.Error: Incorrect padding
2017-10-06 07:38:22 WARNING (MainThread) [homeassistant.components.media_player] Setup of platform cast is taking over 10 seconds.
2017-10-06 07:38:39 WARNING (MainThread) [homeassistant.components.switch] Setup of platform broadlink is taking over 10 seconds.
2017-10-06 07:38:39 ERROR (SyncWorker_15) [homeassistant.components.switch.broadlink] Failed to connect to device
2017-10-06 07:38:43 ERROR (SyncWorker_9) [homeassistant.components.light.yeelight] Failed to connect to bulb 192.168.1.203, Hallway left: A socket error occurred when sending the command.
2017-10-06 07:38:47 WARNING (SyncWorker_13) [homeassistant.components.light.flux_led] Failed to connect to bulb 192.168.1.146, strip
2017-10-06 07:38:48 WARNING (MainThread) [homeassistant.components.sensor.template] Could not render template Queries Today, the state is unknown.
2017-10-06 07:38:48 WARNING (MainThread) [homeassistant.components.sensor.template] Could not render template Percentage Today, the state is unknown.
2017-10-06 07:38:48 WARNING (MainThread) [homeassistant.components.sensor.template] Could not render template Domains Blocked, the state is unknown.
2017-10-06 07:38:48 WARNING (MainThread) [homeassistant.components.sensor.template] Could not render template Last Motion, the state is unknown.
2017-10-06 07:38:48 WARNING (MainThread) [homeassistant.components.sensor.template] Could not render template Office Temperature, the state is unknown.
2017-10-06 07:38:48 WARNING (MainThread) [homeassistant.components.sensor.template] Could not render template Livingroom Temperature, the state is unknown.
2017-10-06 07:38:48 WARNING (MainThread) [homeassistant.helpers.entity] Update for sensor.air_gilma_daily_energy_last_24h is already in progress
2017-10-06 07:38:48 WARNING (SyncWorker_6) [homeassistant.components.sensor.file] File or data not present at the moment: upslog.txt
2017-10-06 07:38:48 WARNING (MainThread) [homeassistant.helpers.entity] Update for sensor.air_gilma_daily_energy_from_0000_to_now is already in progress
2017-10-06 07:38:48 WARNING (MainThread) [homeassistant.helpers.entity] Update for sensor.air_guest_room_daily_energy_last_24h is already in progress
2017-10-06 07:38:48 WARNING (MainThread) [homeassistant.helpers.entity] Update for sensor.air_guest_room_daily_energy_from_0000_to_now is already in progress
2017-10-06 07:38:49 WARNING (MainThread) [homeassistant.components.sensor] Updating rest sensor took longer than the scheduled update interval 0:00:01
2017-10-06 07:38:58 WARNING (MainThread) [homeassistant.components.sensor] Updating rest sensor took longer than the scheduled update interval 0:00:01
2017-10-06 07:38:58 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.flower_1_conductivity is taking over 10 seconds
2017-10-06 07:39:07 WARNING (MainThread) [homeassistant.helpers.entity] Update of switch.marisol_heater is taking over 10 seconds
2017-10-06 07:39:09 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.flower_1_battery is taking over 10 seconds
2017-10-06 07:39:11 WARNING (MainThread) [homeassistant.components.sensor] Updating rest sensor took longer than the scheduled update interval 0:00:01
2017-10-06 07:39:13 WARNING (SyncWorker_17) [netdisco.ssdp] Error fetching description at http://192.168.1.241:8200/rootDesc.xml
2017-10-06 07:39:13 WARNING (SyncWorker_17) [netdisco.ssdp] Found malformed XML at http://192.168.1.30:9080: status=ok
2017-10-06 07:39:16 WARNING (MainThread) [homeassistant.components.sensor] Updating rest sensor took longer than the scheduled update interval 0:00:01
2017-10-06 07:39:17 ERROR (MainThread) [homeassistant.components.light] Error while setting up platform hue
Traceback (most recent call last):
  File "/usr/lib/python3.6/site-packages/homeassistant/helpers/entity_component.py", line 164, in _async_setup_platform
    SLOW_SETUP_MAX_WAIT, loop=self.hass.loop)
  File "/usr/lib/python3.6/asyncio/tasks.py", line 352, in wait_for
    return fut.result()
  File "/usr/lib/python3.6/asyncio/futures.py", line 244, in result
    raise self._exception
  File "/usr/lib/python3.6/concurrent/futures/thread.py", line 55, in run
    result = self.fn(*self.args, **self.kwargs)
  File "/usr/lib/python3.6/site-packages/homeassistant/components/light/hue.py", line 138, in setup_platform
    allow_in_emulated_hue, allow_hue_groups)
  File "/usr/lib/python3.6/site-packages/homeassistant/components/light/hue.py", line 149, in setup_bridge
    config_file_path=hass.config.path(filename))
  File "/usr/lib/python3.6/site-packages/phue.py", line 629, in __init__
    self.connect()
  File "/usr/lib/python3.6/site-packages/phue.py", line 752, in connect
    self.register_app()
  File "/usr/lib/python3.6/site-packages/phue.py", line 706, in register_app
    response = self.request('POST', '/api', registration_request)
  File "/usr/lib/python3.6/site-packages/phue.py", line 667, in request
    return json.loads(response.decode('utf-8'))
  File "/usr/lib/python3.6/json/__init__.py", line 354, in loads
    return _default_decoder.decode(s)
  File "/usr/lib/python3.6/json/decoder.py", line 339, in decode
    obj, end = self.raw_decode(s, idx=_w(s, 0).end())
  File "/usr/lib/python3.6/json/decoder.py", line 357, in raw_decode
    raise JSONDecodeError("Expecting value", s, err.value) from None
json.decoder.JSONDecodeError: Expecting value: line 1 column 1 (char 0)
2017-10-06 07:39:18 WARNING (SyncWorker_3) [homeassistant.components.zwave] zwave not ready after 30 seconds, continuing anyway
2017-10-06 07:39:19 WARNING (SyncWorker_9) [homeassistant.components.sensor.file] File or data not present at the moment: upslog.txt
2017-10-06 07:39:20 WARNING (MainThread) [homeassistant.components.sensor.template] Could not render template Last Motion, the state is unknown.
2017-10-06 07:39:21 WARNING (MainThread) [homeassistant.components.sensor] Updating rest sensor took longer than the scheduled update interval 0:00:01
2017-10-06 07:39:24 ERROR (SyncWorker_3) [plexapi] http://192.168.1.23:32400: (401) unauthorized
2017-10-06 07:39:25 WARNING (MainThread) [homeassistant.components.sensor] Updating rest sensor took longer than the scheduled update interval 0:00:01
2017-10-06 07:39:27 WARNING (MainThread) [homeassistant.components.media_player] Setup of platform cast is taking over 10 seconds.
2017-10-06 07:39:27 WARNING (MainThread) [homeassistant.components.media_player] Setup of platform cast is taking over 10 seconds.
2017-10-06 07:39:28 WARNING (MainThread) [homeassistant.components.switch] Updating broadlink switch took longer than the scheduled update interval 0:00:30
2017-10-06 07:39:29 WARNING (MainThread) [homeassistant.components.sensor] Updating rest sensor took longer than the scheduled update interval 0:00:01
2017-10-06 07:39:29 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.flower_1_battery is taking over 10 seconds
2017-10-06 07:39:32 ERROR (SyncWorker_19) [homeassistant.core] Error doing job: Future exception was never retrieved
Traceback (most recent call last):
  File "/usr/lib/python3.6/concurrent/futures/thread.py", line 55, in run
    result = self.fn(*self.args, **self.kwargs)
  File "/usr/lib/python3.6/site-packages/homeassistant/components/sensor/synologydsm.py", line 114, in run_setup
    for volume in volumes:
TypeError: 'NoneType' object is not iterable
2017-10-06 07:39:32 ERROR (SyncWorker_10) [pynetgear] Error talking to API
Traceback (most recent call last):
  File "/usr/lib/python3.6/site-packages/requests/models.py", line 374, in prepare_url
    scheme, auth, host, port, path, query, fragment = parse_url(url)
  File "/usr/lib/python3.6/site-packages/requests/packages/urllib3/util/url.py", line 199, in parse_url
    raise LocationParseError(url)
requests.packages.urllib3.exceptions.LocationParseError: Failed to parse: None:None

During handling of the above exception, another exception occurred:

Traceback (most recent call last):
  File "/usr/lib/python3.6/site-packages/pynetgear/__init__.py", line 113, in _make_request
    self.soap_url, headers=headers, data=message, timeout=10)
  File "/usr/lib/python3.6/site-packages/requests/api.py", line 112, in post
    return request('post', url, data=data, json=json, **kwargs)
  File "/usr/lib/python3.6/site-packages/requests/api.py", line 58, in request
    return session.request(method=method, url=url, **kwargs)
  File "/usr/lib/python3.6/site-packages/requests/sessions.py", line 504, in request
    prep = self.prepare_request(req)
  File "/usr/lib/python3.6/site-packages/requests/sessions.py", line 436, in prepare_request
    hooks=merge_hooks(request.hooks, self.hooks),
  File "/usr/lib/python3.6/site-packages/requests/models.py", line 302, in prepare
    self.prepare_url(url, params)
  File "/usr/lib/python3.6/site-packages/requests/models.py", line 376, in prepare_url
    raise InvalidURL(*e.args)
requests.exceptions.InvalidURL: Failed to parse: None:None
2017-10-06 07:39:32 ERROR (SyncWorker_10) [homeassistant.components.device_tracker.netgear] Failed to Login
2017-10-06 07:39:32 ERROR (MainThread) [homeassistant.components.device_tracker] Error setting up platform netgear
2017-10-06 07:39:49 WARNING (SyncWorker_9) [homeassistant.components.sensor.file] File or data not present at the moment: upslog.txt
2017-10-06 07:39:59 WARNING (MainThread) [homeassistant.components.sensor.template] Could not render template Last Motion, the state is unknown.
2017-10-06 07:39:59 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.flower_1_battery is taking over 10 seconds
2017-10-06 07:40:02 WARNING (MainThread) [homeassistant.components.sensor.template] Could not render template Last Motion, the state is unknown.
2017-10-06 07:40:08 WARNING (MainThread) [homeassistant.helpers.entity] Update of switch.marisol_heater is taking over 10 seconds
2017-10-06 07:40:08 WARNING (MainThread) [homeassistant.components.sensor.template] Could not render template Last Motion, the state is unknown.
2017-10-06 07:40:20 WARNING (SyncWorker_6) [homeassistant.components.sensor.file] File or data not present at the moment: upslog.txt
2017-10-06 07:40:25 WARNING (MainThread) [homeassistant.components.sensor.template] Could not render template Last Motion, the state is unknown.
2017-10-06 07:40:29 WARNING (MainThread) [homeassistant.components.switch] Updating broadlink switch took longer than the scheduled update interval 0:00:30
2017-10-06 07:40:30 WARNING (MainThread) [homeassistant.helpers.entity] Update of sensor.flower_1_battery is taking over 10 seconds
2017-10-06 07:40:31 WARNING (MainThread) [homeassistant.components.sensor] Updating rest sensor took longer than the scheduled update interva
2

Hi @anon35356645 it’s the same you’ve posted here:

and you wrote

I made a fresh installation, no logs

Did they come back after a while (or reboot)?

3

Well this is what I had:

  • HASSBIAN and started to have problems
  • I format another SD card and put HASSIO
  • Now have problem with HASSIO but I cannot tell if are the same as when it was Hassbian (or with hassbian maybe the problem was the SD card, who knows).

In my new installation I noticed that Hassio starts, but after very short time it stops: so I can reach 192.168.1.238:8123 but for like a minute or so), during this time everything seems to work (can’t tell exactly because its really short time)

I think that before I did not realize that for a minute it was reachable, so it has to be some problem in the configuration or bug??

4

So definetely something is making HASSIO stop (could be also hassbian before but I am nto sure), but log does not show anything specific.

Any way to put in logger: some commands to get a better log?

5

Did you copy your config from hassbian or is it a clean install?

Check the docs for logger settings, you can set it to debug.

6

copy from hassbian. But Hassbian worked well until yesterday, no change on it yesterday

7

I don’t know, only strange error is this:

2017-10-06 10:42:46 ERROR (MainThread) [homeassistant.components.light] Error while setting up platform hue
Traceback (most recent call last):
  File "/usr/lib/python3.6/site-packages/homeassistant/helpers/entity_component.py", line 164, in _async_setup_platform
    SLOW_SETUP_MAX_WAIT, loop=self.hass.loop)
  File "/usr/lib/python3.6/asyncio/tasks.py", line 352, in wait_for
    return fut.result()
  File "/usr/lib/python3.6/asyncio/futures.py", line 244, in result
    raise self._exception
  File "/usr/lib/python3.6/concurrent/futures/thread.py", line 55, in run
    result = self.fn(*self.args, **self.kwargs)
  File "/usr/lib/python3.6/site-packages/homeassistant/components/light/hue.py", line 138, in setup_platform
    allow_in_emulated_hue, allow_hue_groups)
  File "/usr/lib/python3.6/site-packages/homeassistant/components/light/hue.py", line 149, in setup_bridge
    config_file_path=hass.config.path(filename))
  File "/usr/lib/python3.6/site-packages/phue.py", line 629, in __init__
    self.connect()
  File "/usr/lib/python3.6/site-packages/phue.py", line 752, in connect
    self.register_app()
  File "/usr/lib/python3.6/site-packages/phue.py", line 706, in register_app
    response = self.request('POST', '/api', registration_request)
  File "/usr/lib/python3.6/site-packages/phue.py", line 667, in request
    return json.loads(response.decode('utf-8'))
  File "/usr/lib/python3.6/json/__init__.py", line 354, in loads
    return _default_decoder.decode(s)
  File "/usr/lib/python3.6/json/decoder.py", line 339, in decode
    obj, end = self.raw_decode(s, idx=_w(s, 0).end())
  File "/usr/lib/python3.6/json/decoder.py", line 357, in raw_decode
    raise JSONDecodeError("Expecting value", s, err.value) from None
json.decoder.JSONDecodeError: Expecting value: line 1 column 1 (char 0)
2017-10-06 10:42:46 DEBUG (Dummy-21) [homeassistant.components.zwave.util] node.generic 16 not in generic_device_class [17, 64]
2017-10-06 10:42:46 DEBUG (SyncWorker_15) [homeassistant.components.light.hue] Getting group 0 from bridge
8

nothing I do.

I took out all errors (now no errors in log), but nevertheless after 3-4 minutes HASSIO stops … and I have to restart

9

nothing in logger