I have the same issue, on Raspbian.
It happens when HA tries to automatically install components it either discovers or I ‘force’ via configuration.yaml such as mqtt:
Nov 26 21:44:50 hass-0001 hass[3468]: ERROR:homeassistant.util.package:Unable to install package paho-mqtt==1.3.1: Exception:
Nov 26 21:44:50 hass-0001 hass[3468]: Traceback (most recent call last):
Nov 26 21:44:50 hass-0001 hass[3468]: File “/srv/homeassistant/share/python-wheels/urllib3-1.19.1-py2.py3-none-any.whl/urllib3/connectionpool.py”, line 350, in _make_request
Nov 26 21:44:50 hass-0001 hass[3468]: self._validate_conn(conn)
Nov 26 21:44:50 hass-0001 hass[3468]: File “/srv/homeassistant/share/python-wheels/urllib3-1.19.1-py2.py3-none-any.whl/urllib3/connectionpool.py”, line 837, in validate_conn
Nov 26 21:44:50 hass-0001 hass[3468]: conn.connect()
Nov 26 21:44:50 hass-0001 hass[3468]: File “/srv/homeassistant/share/python-wheels/urllib3-1.19.1-py2.py3-none-any.whl/urllib3/connection.py”, line 323, in connect
Nov 26 21:44:50 hass-0001 hass[3468]: ssl_context=context)
Nov 26 21:44:50 hass-0001 hass[3468]: File "/srv/homeassistant/share/python-wheels/urllib3-1.19.1-py2.py3-none-any.whl/urllib3/util/ssl.py", line 324, in ssl_wrap_socket
Nov 26 21:44:50 hass-0001 hass[3468]: return context.wrap_socket(sock, server_hostname=server_hostname)
Nov 26 21:44:50 hass-0001 hass[3468]: File “/usr/lib/python3.5/ssl.py”, line 385, in wrap_socket
Nov 26 21:44:50 hass-0001 hass[3468]: _context=self)
Nov 26 21:44:50 hass-0001 hass[3468]: File “/usr/lib/python3.5/ssl.py”, line 760, in init
Nov 26 21:44:50 hass-0001 hass[3468]: self.do_handshake()
Nov 26 21:44:50 hass-0001 hass[3468]: File “/usr/lib/python3.5/ssl.py”, line 996, in do_handshake
Nov 26 21:44:50 hass-0001 hass[3468]: self._sslobj.do_handshake()
Nov 26 21:44:50 hass-0001 hass[3468]: File “/usr/lib/python3.5/ssl.py”, line 641, in do_handshake
Nov 26 21:44:50 hass-0001 hass[3468]: self._sslobj.do_handshake()
Nov 26 21:44:50 hass-0001 hass[3468]: socket.timeout: _ssl.c:704: The handshake operation timed out
Nov 26 21:44:50 hass-0001 hass[3468]: During handling of the above exception, another exception occurred:
Nov 26 21:44:50 hass-0001 hass[3468]: Traceback (most recent call last):
Nov 26 21:44:50 hass-0001 hass[3468]: File “/srv/homeassistant/share/python-wheels/urllib3-1.19.1-py2.py3-none-any.whl/urllib3/connectionpool.py”, line 594, in urlopen
Nov 26 21:44:50 hass-0001 hass[3468]: chunked=chunked)
Nov 26 21:44:50 hass-0001 hass[3468]: File “/srv/homeassistant/share/python-wheels/urllib3-1.19.1-py2.py3-none-any.whl/urllib3/connectionpool.py”, line 353, in _make_request
Nov 26 21:44:50 hass-0001 hass[3468]: self._raise_timeout(err=e, url=url, timeout_value=conn.timeout)
Nov 26 21:44:50 hass-0001 hass[3468]: File “/srv/homeassistant/share/python-wheels/urllib3-1.19.1-py2.py3-none-any.whl/urllib3/connectionpool.py”, line 313, in _raise_timeout
Nov 26 21:44:50 hass-0001 hass[3468]: raise ReadTimeoutError(self, url, “Read timed out. (read timeout=%s)” % timeout_value)
Nov 26 21:44:50 hass-0001 hass[3468]: requests.packages.urllib3.exceptions.ReadTimeoutError: HTTPSConnectionPool(host=‘www.piwheels.hostedpi.com’, port=443): Read timed out. (read timeout=15)
Nov 26 21:44:50 hass-0001 hass[3468]: During handling of the above exception, another exception occurred:
Nov 26 21:44:50 hass-0001 hass[3468]: Traceback (most recent call last):
Nov 26 21:44:50 hass-0001 hass[3468]: File “/srv/homeassistant/lib/python3.5/site-packages/pip/basecommand.py”, line 215, in main
Nov 26 21:44:50 hass-0001 hass[3468]: status = self.run(options, args)
Nov 26 21:44:50 hass-0001 hass[3468]: File “/srv/homeassistant/lib/python3.5/site-packages/pip/commands/install.py”, line 353, in run
Nov 26 21:44:50 hass-0001 hass[3468]: wb.build(autobuilding=True)
Nov 26 21:44:50 hass-0001 hass[3468]: File “/srv/homeassistant/lib/python3.5/site-packages/pip/wheel.py”, line 749, in build
Nov 26 21:44:50 hass-0001 hass[3468]: self.requirement_set.prepare_files(self.finder)
Nov 26 21:44:50 hass-0001 hass[3468]: File “/srv/homeassistant/lib/python3.5/site-packages/pip/req/req_set.py”, line 380, in prepare_files
Nov 26 21:44:50 hass-0001 hass[3468]: ignore_dependencies=self.ignore_dependencies))
Nov 26 21:44:50 hass-0001 hass[3468]: File “/srv/homeassistant/lib/python3.5/site-packages/pip/req/req_set.py”, line 554, in _prepare_file
Nov 26 21:44:50 hass-0001 hass[3468]: require_hashes
Nov 26 21:44:50 hass-0001 hass[3468]: File “/srv/homeassistant/lib/python3.5/site-packages/pip/req/req_install.py”, line 278, in populate_link
Nov 26 21:44:50 hass-0001 hass[3468]: self.link = finder.find_requirement(self, upgrade)
Nov 26 21:44:50 hass-0001 hass[3468]: File “/srv/homeassistant/lib/python3.5/site-packages/pip/index.py”, line 465, in find_requirement
Nov 26 21:44:50 hass-0001 hass[3468]: all_candidates = self.find_all_candidates(req.name)
Nov 26 21:44:50 hass-0001 hass[3468]: File “/srv/homeassistant/lib/python3.5/site-packages/pip/index.py”, line 423, in find_all_candidates
Nov 26 21:44:50 hass-0001 hass[3468]: for page in self._get_pages(url_locations, project_name):
Nov 26 21:44:50 hass-0001 hass[3468]: File “/srv/homeassistant/lib/python3.5/site-packages/pip/index.py”, line 568, in _get_pages
Nov 26 21:44:50 hass-0001 hass[3468]: page = self._get_page(location)
Nov 26 21:44:50 hass-0001 hass[3468]: File “/srv/homeassistant/lib/python3.5/site-packages/pip/index.py”, line 683, in _get_page
Nov 26 21:44:50 hass-0001 hass[3468]: return HTMLPage.get_page(link, session=self.session)
Nov 26 21:44:50 hass-0001 hass[3468]: File “/srv/homeassistant/lib/python3.5/site-packages/pip/index.py”, line 792, in get_page
Nov 26 21:44:50 hass-0001 hass[3468]: “Cache-Control”: “max-age=600”,
Nov 26 21:44:50 hass-0001 hass[3468]: File “/srv/homeassistant/share/python-wheels/requests-2.12.4-py2.py3-none-any.whl/requests/sessions.py”, line 501, in get
Nov 26 21:44:50 hass-0001 hass[3468]: return self.request(‘GET’, url, **kwargs)
Nov 26 21:44:50 hass-0001 hass[3468]: File “/srv/homeassistant/lib/python3.5/site-packages/pip/download.py”, line 386, in request
Nov 26 21:44:50 hass-0001 hass[3468]: return super(PipSession, self).request(method, url, *args, **kwargs)
Nov 26 21:44:50 hass-0001 hass[3468]: File “/srv/homeassistant/share/python-wheels/requests-2.12.4-py2.py3-none-any.whl/requests/sessions.py”, line 488, in request
Nov 26 21:44:50 hass-0001 hass[3468]: resp = self.send(prep, **send_kwargs)
Nov 26 21:44:50 hass-0001 hass[3468]: File “/srv/homeassistant/share/python-wheels/requests-2.12.4-py2.py3-none-any.whl/requests/sessions.py”, line 609, in send
Nov 26 21:44:50 hass-0001 hass[3468]: r = adapter.send(request, **kwargs)
Nov 26 21:44:50 hass-0001 hass[3468]: File “/srv/homeassistant/share/python-wheels/CacheControl-0.11.7-py2.py3-none-any.whl/cachecontrol/adapter.py”, line 47, in send
Nov 26 21:44:50 hass-0001 hass[3468]: resp = super(CacheControlAdapter, self).send(request, **kw)
Nov 26 21:44:50 hass-0001 hass[3468]: File “/srv/homeassistant/share/python-wheels/requests-2.12.4-py2.py3-none-any.whl/requests/adapters.py”, line 423, in send
Nov 26 21:44:50 hass-0001 hass[3468]: timeout=timeout
Nov 26 21:44:50 hass-0001 hass[3468]: File “/srv/homeassistant/share/python-wheels/urllib3-1.19.1-py2.py3-none-any.whl/urllib3/connectionpool.py”, line 643, in urlopen
Nov 26 21:44:50 hass-0001 hass[3468]: _stacktrace=sys.exc_info()[2])
Nov 26 21:44:50 hass-0001 hass[3468]: File “/srv/homeassistant/share/python-wheels/urllib3-1.19.1-py2.py3-none-any.whl/urllib3/util/retry.py”, line 315, in increment
Nov 26 21:44:50 hass-0001 hass[3468]: total -= 1
Nov 26 21:44:50 hass-0001 hass[3468]: TypeError: unsupported operand type(s) for -=: ‘Retry’ and ‘int’
Nov 26 21:44:50 hass-0001 hass[3468]: ERROR:homeassistant.setup:Not initializing mqtt because could not install dependency paho-mqtt==1.3.1
Nov 26 21:44:50 hass-0001 hass[3468]: ERROR:homeassistant.setup:Setup failed for mqtt: Could not install all requirements.
Nov 26 21:44:50 hass-0001 hass[3468]: INFO:homeassistant.core:Bus:Handling <Event call_service[L]: service=create, service_data=message=The following components and platforms could not be set up:
Nov 26 21:44:50 hass-0001 hass[3468]: - mqtt
Nov 26 21:44:50 hass-0001 hass[3468]: Please check your config., title=Invalid config, notification_id=invalid_config, domain=persistent_notification, service_call_id=1972966960-2>
Nov 26 21:44:50 hass-0001 hass[3468]: INFO:homeassistant.core:Bus:Handling <Event call_service[L]: service=create, service_data=message=The following components and platforms could not be set up:
Nov 26 21:44:50 hass-0001 hass[3468]: - mqtt
Nov 26 21:44:50 hass-0001 hass[3468]: Please check your config., title=Invalid config, notification_id=invalid_config, domain=persistent_notification, service_call_id=1972966960-3>
Nov 26 21:44:50 hass-0001 hass[3468]: INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: old_state=None, entity_id=persistent_notification.invalid_config, new_state=<state persistent_notification.invalid_config=notifying; message=The following components and platforms could not be set up:
Nov 26 21:44:50 hass-0001 hass[3468]: - mqtt
Nov 26 21:44:50 hass-0001 hass[3468]: Please check your config., title=Invalid config @ 2017-11-26T21:44:50.550123-08:00>>
Nov 26 21:44:50 hass-0001 hass[3468]: INFO:homeassistant.core:Bus:Handling <Event service_executed[L]: service_call_id=1972966960-2>
Nov 26 21:44:50 hass-0001 hass[3468]: INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: old_state=<state persistent_notification.invalid_config=notifying; message=The following components and platforms could not be set up:
Nov 26 21:44:50 hass-0001 hass[3468]: - mqtt
Nov 26 21:44:50 hass-0001 hass[3468]: Please check your config., title=Invalid config @ 2017-11-26T21:44:50.550123-08:00>, entity_id=persistent_notification.invalid_config, new_state=<state persistent_notification.invalid_config=notifying; message=The following components and platforms could not be set up:
Nov 26 21:44:50 hass-0001 hass[3468]: - mqtt
Nov 26 21:44:50 hass-0001 hass[3468]: Please check your config., title=Invalid config @ 2017-11-26T21:44:50.550123-08:00>>
I also tried to run several tests on OS level:
sudo pip3 install paho-mqtt==1.3.1
sudo pip3 install pytest-runner
sudo pip3 install --index-url=http://pypi.python.org/simple/ --trusted-host pypi.python.org pytest-runner
All of them resulted in the same type of issues:
Exception:
Traceback (most recent call last):
File “/usr/share/python-wheels/urllib3-1.19.1-py2.py3-none-any.whl/urllib3/connectionpool.py”, line 350, in _make_request
self._validate_conn(conn)
File “/usr/share/python-wheels/urllib3-1.19.1-py2.py3-none-any.whl/urllib3/connectionpool.py”, line 837, in validate_conn
conn.connect()
File “/usr/share/python-wheels/urllib3-1.19.1-py2.py3-none-any.whl/urllib3/connection.py”, line 323, in connect
ssl_context=context)
File "/usr/share/python-wheels/urllib3-1.19.1-py2.py3-none-any.whl/urllib3/util/ssl.py", line 324, in ssl_wrap_socket
return context.wrap_socket(sock, server_hostname=server_hostname)
File “/usr/lib/python3.5/ssl.py”, line 385, in wrap_socket
_context=self)
File “/usr/lib/python3.5/ssl.py”, line 760, in init
self.do_handshake()
File “/usr/lib/python3.5/ssl.py”, line 996, in do_handshake
self._sslobj.do_handshake()
File “/usr/lib/python3.5/ssl.py”, line 641, in do_handshake
self._sslobj.do_handshake()
socket.timeout: _ssl.c:704: The handshake operation timed out
During handling of the above exception, another exception occurred:
Traceback (most recent call last):
File “/usr/share/python-wheels/urllib3-1.19.1-py2.py3-none-any.whl/urllib3/connectionpool.py”, line 594, in urlopen
chunked=chunked)
File “/usr/share/python-wheels/urllib3-1.19.1-py2.py3-none-any.whl/urllib3/connectionpool.py”, line 353, in _make_request
self._raise_timeout(err=e, url=url, timeout_value=conn.timeout)
File “/usr/share/python-wheels/urllib3-1.19.1-py2.py3-none-any.whl/urllib3/connectionpool.py”, line 313, in _raise_timeout
raise ReadTimeoutError(self, url, “Read timed out. (read timeout=%s)” % timeout_value)
requests.packages.urllib3.exceptions.ReadTimeoutError: HTTPSConnectionPool(host=‘www.piwheels.hostedpi.com’, port=443): Read timed out. (read timeout=15)
During handling of the above exception, another exception occurred:
Traceback (most recent call last):
File “/usr/lib/python3/dist-packages/pip/basecommand.py”, line 215, in main
status = self.run(options, args)
File “/usr/lib/python3/dist-packages/pip/commands/install.py”, line 353, in run
wb.build(autobuilding=True)
File “/usr/lib/python3/dist-packages/pip/wheel.py”, line 749, in build
self.requirement_set.prepare_files(self.finder)
File “/usr/lib/python3/dist-packages/pip/req/req_set.py”, line 380, in prepare_files
ignore_dependencies=self.ignore_dependencies))
File “/usr/lib/python3/dist-packages/pip/req/req_set.py”, line 554, in _prepare_file
require_hashes
File “/usr/lib/python3/dist-packages/pip/req/req_install.py”, line 278, in populate_link
self.link = finder.find_requirement(self, upgrade)
File “/usr/lib/python3/dist-packages/pip/index.py”, line 465, in find_requirement
all_candidates = self.find_all_candidates(req.name)
File “/usr/lib/python3/dist-packages/pip/index.py”, line 423, in find_all_candidates
for page in self._get_pages(url_locations, project_name):
File “/usr/lib/python3/dist-packages/pip/index.py”, line 568, in _get_pages
page = self._get_page(location)
File “/usr/lib/python3/dist-packages/pip/index.py”, line 683, in _get_page
return HTMLPage.get_page(link, session=self.session)
File “/usr/lib/python3/dist-packages/pip/index.py”, line 792, in get_page
“Cache-Control”: “max-age=600”,
File “/usr/share/python-wheels/requests-2.12.4-py2.py3-none-any.whl/requests/sessions.py”, line 501, in get
return self.request(‘GET’, url, **kwargs)
File “/usr/lib/python3/dist-packages/pip/download.py”, line 386, in request
return super(PipSession, self).request(method, url, *args, **kwargs)
File “/usr/share/python-wheels/requests-2.12.4-py2.py3-none-any.whl/requests/sessions.py”, line 488, in request
resp = self.send(prep, **send_kwargs)
File “/usr/share/python-wheels/requests-2.12.4-py2.py3-none-any.whl/requests/sessions.py”, line 609, in send
r = adapter.send(request, **kwargs)
File “/usr/share/python-wheels/CacheControl-0.11.7-py2.py3-none-any.whl/cachecontrol/adapter.py”, line 47, in send
resp = super(CacheControlAdapter, self).send(request, **kw)
File “/usr/share/python-wheels/requests-2.12.4-py2.py3-none-any.whl/requests/adapters.py”, line 423, in send
timeout=timeout
File “/usr/share/python-wheels/urllib3-1.19.1-py2.py3-none-any.whl/urllib3/connectionpool.py”, line 643, in urlopen
_stacktrace=sys.exc_info()[2])
File “/usr/share/python-wheels/urllib3-1.19.1-py2.py3-none-any.whl/urllib3/util/retry.py”, line 315, in increment
total -= 1
TypeError: unsupported operand type(s) for -=: ‘Retry’ and ‘int’
Any thoughts??