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
69.94k stars 29.03k forks source link

Websocket API errors since upgrade to .34 #4725

Closed rpitera closed 7 years ago

rpitera commented 7 years ago

Home Assistant release (hass --version): 0.34 0.34.1

Python release (python3 --version): 3.4.2

Description of problem: I am getting a number of errors mentioning the new websockets api since upgrading from 0.33.4 to 0.34

16-12-04 13:00:00 homeassistant.core: Error doing job: Future exception was never retrieved
Traceback (most recent call last):
  File "/usr/lib/python3.4/concurrent/futures/thread.py", line 54, in run
    result = self.fn(*self.args, **self.kwargs)
  File "/srv/hass/hass_venv/lib/python3.4/site-packages/homeassistant/components/websocket_api.py", line 246, in _forward_event
    self.send_message(event_message(iden, event))
  File "/srv/hass/hass_venv/lib/python3.4/site-packages/homeassistant/components/websocket_api.py", line 221, in send_message
    self.wsock.send_json(message, dumps=JSON_DUMP)
  File "/home/hass/deps/aiohttp/web_ws.py", line 169, in send_json
    self.send_str(dumps(data))
  File "/home/hass/deps/aiohttp/web_ws.py", line 156, in send_str
    self._writer.send(data, binary=False)
  File "/home/hass/deps/aiohttp/_ws_impl.py", line 354, in send
    self._send_frame(message, WSMsgType.TEXT)
  File "/home/hass/deps/aiohttp/_ws_impl.py", line 333, in _send_frame
    self.writer.write(header + message)
  File "/usr/lib/python3.4/asyncio/selector_events.py", line 898, in write
    self._buffer.extend(data)
BufferError: Existing exports of data: object cannot be re-sized
16-12-04 13:00:00 homeassistant.core: Error doing job: Future exception was never retrieved
Traceback (most recent call last):
  File "/usr/lib/python3.4/concurrent/futures/thread.py", line 54, in run
    result = self.fn(*self.args, **self.kwargs)
  File "/srv/hass/hass_venv/lib/python3.4/site-packages/homeassistant/components/websocket_api.py", line 246, in _forward_event
    self.send_message(event_message(iden, event))
  File "/srv/hass/hass_venv/lib/python3.4/site-packages/homeassistant/components/websocket_api.py", line 221, in send_message
    self.wsock.send_json(message, dumps=JSON_DUMP)
  File "/home/hass/deps/aiohttp/web_ws.py", line 169, in send_json
    self.send_str(dumps(data))
  File "/home/hass/deps/aiohttp/web_ws.py", line 156, in send_str
    self._writer.send(data, binary=False)
  File "/home/hass/deps/aiohttp/_ws_impl.py", line 354, in send
    self._send_frame(message, WSMsgType.TEXT)
  File "/home/hass/deps/aiohttp/_ws_impl.py", line 333, in _send_frame
    self.writer.write(header + message)
  File "/usr/lib/python3.4/asyncio/selector_events.py", line 898, in write
    self._buffer.extend(data)
BufferError: Existing exports of data: object cannot be re-sized
16-12-04 13:03:02 homeassistant.core: Error doing job: Future exception was never retrieved
Traceback (most recent call last):
  File "/usr/lib/python3.4/concurrent/futures/thread.py", line 54, in run
    result = self.fn(*self.args, **self.kwargs)
  File "/srv/hass/hass_venv/lib/python3.4/site-packages/homeassistant/components/websocket_api.py", line 246, in _forward_event
    self.send_message(event_message(iden, event))
  File "/srv/hass/hass_venv/lib/python3.4/site-packages/homeassistant/components/websocket_api.py", line 221, in send_message
    self.wsock.send_json(message, dumps=JSON_DUMP)
  File "/home/hass/deps/aiohttp/web_ws.py", line 169, in send_json
    self.send_str(dumps(data))
  File "/home/hass/deps/aiohttp/web_ws.py", line 156, in send_str
    self._writer.send(data, binary=False)
  File "/home/hass/deps/aiohttp/_ws_impl.py", line 354, in send
    self._send_frame(message, WSMsgType.TEXT)
  File "/home/hass/deps/aiohttp/_ws_impl.py", line 333, in _send_frame
    self.writer.write(header + message)
  File "/usr/lib/python3.4/asyncio/selector_events.py", line 898, in write
    self._buffer.extend(data)
BufferError: Existing exports of data: object cannot be re-sized
16-12-04 13:05:31 homeassistant.core: Error doing job: Future exception was never retrieved
Traceback (most recent call last):
  File "/usr/lib/python3.4/concurrent/futures/thread.py", line 54, in run
    result = self.fn(*self.args, **self.kwargs)
  File "/srv/hass/hass_venv/lib/python3.4/site-packages/homeassistant/components/websocket_api.py", line 246, in _forward_event
    self.send_message(event_message(iden, event))
  File "/srv/hass/hass_venv/lib/python3.4/site-packages/homeassistant/components/websocket_api.py", line 221, in send_message
    self.wsock.send_json(message, dumps=JSON_DUMP)
  File "/home/hass/deps/aiohttp/web_ws.py", line 169, in send_json
    self.send_str(dumps(data))
  File "/home/hass/deps/aiohttp/web_ws.py", line 156, in send_str
    self._writer.send(data, binary=False)
  File "/home/hass/deps/aiohttp/_ws_impl.py", line 354, in send
    self._send_frame(message, WSMsgType.TEXT)
  File "/home/hass/deps/aiohttp/_ws_impl.py", line 333, in _send_frame
    self.writer.write(header + message)
  File "/usr/lib/python3.4/asyncio/selector_events.py", line 898, in write
    self._buffer.extend(data)
BufferError: Existing exports of data: object cannot be re-sized

Also, I am no longer seeing the "Streaming Updates" toggle in the UI although sensors do appear to be updating states. Not sure if this is related or if this was a UI change that wasn't announced.

Expected: To see the Streaming Updates button and not see the errors above

rpitera commented 7 years ago

Streaming Updates button may be a separate issue; cross referencing to #4726

rpitera commented 7 years ago

Just learned that the Streaming updates toggle has been removed so that's not an error. Adjusted my title.

rpitera commented 7 years ago

Updated to 0.34.1 and still getting the same errors.

balloob commented 7 years ago

The $100 question is: does the streaming still work? Do you get states pushed to your frontend when they change ?

rpitera commented 7 years ago

They appear to be working for the most part now; I had seen some latency with state updates on a couple of my Wink and ZWave sensors, but that seems to have gone away. I was going to update to 0.34.2 but I'll hold off until you say so - I don't want to complicate the issue.

Edit: I'm going to go ahead and update as another user posted the same issue in the forums on 0.34.2 so ti doesn't seem to make a difference.

jvrproductions commented 7 years ago

my stop working after update from 34.0 to 34.2 before stop working i was only getting this error

16-12-04 21:21:29 homeassistant.core: Error doing job: Future exception was never retrieved
Traceback (most recent call last):
File "/usr/lib/python3.4/concurrent/futures/thread.py", line 54, in run
result = self.fn(*self.args, **self.kwargs)
File "/srv/hass/hass_venv/lib/python3.4/site-packages/homeassistant/components/websocket_api.py", line 246, in forwardevent
self.send_message(event_message(iden, event))
File "/srv/hass/hass_venv/lib/python3.4/site-packages/homeassistant/components/websocket_api.py", line 221, in send_message
self.wsock.send_json(message, dumps=JSON_DUMP)
File "/home/hass/.homeassistant/deps/aiohttp/web_ws.py", line 169, in send_json
self.send_str(dumps(data))
File "/home/hass/.homeassistant/deps/aiohttp/web_ws.py", line 156, in send_str
self._writer.send(data, binary=False)
File "/home/hass/.homeassistant/deps/aiohttp/_ws_impl.py", line 354, in send
self.sendframe(message, WSMsgType.TEXT)
File "/home/hass/.homeassistant/deps/aiohttp/_ws_impl.py", line 333, in sendframe
self.writer.write(header + message)
File "/usr/lib/python3.4/asyncio/selector_events.py", line 898, in write
self._buffer.extend(data)
BufferError: Existing exports of data: object cannot be re-sized

Any help will be great thanks :-)

balloob commented 7 years ago

Could you turn on the debug logging for the websocket api:

logger:
  logs:
    homeassistant.components.websocket_api: debug

And when posting the next error, also post the events leading up to the exception happening.

rpitera commented 7 years ago

Upgraded to 0.34.2 and have setup debug to catch the next error.

rpitera commented 7 years ago

Error came about 5 minutes after restarting:

16-12-05 09:06:18 homeassistant.components.websocket_api: WS 1829311472: Connected 
16-12-05 09:06:18 homeassistant.components.websocket_api: WS 1829311472: Sending {'ha_version': '0.34.2', 'type': 'auth_required'}
16-12-05 09:06:19 homeassistant.components.websocket_api: WS 1829311472: Sending {'ha_version': '0.34.2', 'type': 'auth_ok'}
16-12-05 09:06:19 homeassistant.components.websocket_api: WS 1829311472: Received {'event_type': 'state_changed', 'id': 2, 'type': 'subscribe_events'}
16-12-05 09:06:19 homeassistant.components.websocket_api: WS 1829311472: Sending {'result': None, 'success': True, 'id': 2, 'type': 'result'}
16-12-05 09:06:19 homeassistant.components.websocket_api: WS 1829311472: Received {'event_type': 'component_loaded', 'id': 3, 'type': 'subscribe_events'}
16-12-05 09:06:19 homeassistant.components.websocket_api: WS 1829311472: Sending {'result': None, 'success': True, 'id': 3, 'type': 'result'}
16-12-05 09:06:19 homeassistant.components.websocket_api: WS 1829311472: Received {'event_type': 'service_registered', 'id': 4, 'type': 'subscribe_events'}
16-12-05 09:06:19 homeassistant.components.websocket_api: WS 1829311472: Sending {'result': None, 'success': True, 'id': 4, 'type': 'result'}
16-12-05 09:06:21 homeassistant.components.websocket_api: WS 1829311472: Sending {'id': 2, 'type': 'event', 'event': {'data': {'entity_id': 'device_tracker.androidad5afe7fb4156e46fiosrouterhome', 'old_state': <state device_tracker.androidad5afe7fb4156e46fiosrouterhome=not_home; friendly_name=android-ad5afe7fb4156e46.fios-router.home @ 2016-12-05T09:00:59.685930-05:00>, 'new_state': <state device_tracker.androidad5afe7fb4156e46fiosrouterhome=home; friendly_name=android-ad5afe7fb4156e46.fios-router.home @ 2016-12-05T09:06:21.309947-05:00>}, 'event_type': 'state_changed', 'time_fired': datetime.datetime(2016, 12, 5, 14, 6, 21, 310132, tzinfo=<UTC>), 'origin': 'LOCAL'}}
16-12-05 09:06:27 homeassistant.components.websocket_api: WS 1829311472: Sending {'id': 2, 'type': 'event', 'event': {'data': {'entity_id': 'sensor.aeotec_multisensor_6_temperature_7_1', 'old_state': <state sensor.aeotec_multisensor_6_temperature_7_1=73.9; battery_level=100, node_id=7, icon=mdi:thermometer-lines, friendly_name=LR Temperature, unit_of_measurement=°F @ 2016-12-05T09:05:03.942527-05:00>, 'new_state': <state sensor.aeotec_multisensor_6_temperature_7_1=74.0; battery_level=100, node_id=7, icon=mdi:thermometer-lines, friendly_name=LR Temperature, unit_of_measurement=°F @ 2016-12-05T09:06:27.031031-05:00>}, 'event_type': 'state_changed', 'time_fired': datetime.datetime(2016, 12, 5, 14, 6, 27, 31185, tzinfo=<UTC>), 'origin': 'LOCAL'}}
16-12-05 09:06:27 homeassistant.components.websocket_api: WS 1829311472: Sending {'id': 2, 'type': 'event', 'event': {'data': {'entity_id': 'sensor.aeotec_multisensor_6_relative_humidity_7_5', 'old_state': <state sensor.aeotec_multisensor_6_relative_humidity_7_5=30.0; battery_level=100, node_id=7, icon=mdi:water-percent, friendly_name=LR Humidity, unit_of_measurement=% @ 2016-12-05T09:04:27.218583-05:00>, 'new_state': <state sensor.aeotec_multisensor_6_relative_humidity_7_5=31.0; battery_level=100, node_id=7, icon=mdi:water-percent, friendly_name=LR Humidity, unit_of_measurement=% @ 2016-12-05T09:06:27.471002-05:00>}, 'event_type': 'state_changed', 'time_fired': datetime.datetime(2016, 12, 5, 14, 6, 27, 471168, tzinfo=<UTC>), 'origin': 'LOCAL'}}
16-12-05 09:06:29 homeassistant.components.websocket_api: WS 1829311472: Sending {'id': 2, 'type': 'event', 'event': {'data': {'entity_id': 'climate.main_floor_2', 'old_state': <state climate.main_floor_2=heat; operation_list=['off', 'heat'], smart_temperature=23.000000000000004, max_temp=77, operation_mode=heat, min_temp=65, current_humidity=31, external_temperature=35, away_mode=off, fan_mode=auto, temperature=73, friendly_name=Main Floor, occupied=True, unit_of_measurement=°F, current_temperature=73 @ 2016-12-05T09:04:22.210442-05:00>, 'new_state': <state climate.main_floor_2=heat; operation_list=['off', 'heat'], smart_temperature=22.88888888888889, max_temp=77, operation_mode=heat, min_temp=65, current_humidity=32.0, external_temperature=36, away_mode=off, fan_mode=auto, temperature=73, friendly_name=Main Floor, occupied=True, unit_of_measurement=°F, current_temperature=73 @ 2016-12-05T09:04:22.210442-05:00>}, 'event_type': 'state_changed', 'time_fired': datetime.datetime(2016, 12, 5, 14, 6, 29, 483776, tzinfo=<UTC>), 'origin': 'LOCAL'}}
16-12-05 09:06:30 homeassistant.components.websocket_api: WS 1829311472: Sending {'id': 2, 'type': 'event', 'event': {'data': {'entity_id': 'sensor.ram_use', 'old_state': <state sensor.ram_use=60.3; icon=mdi:memory, friendly_name=RAM Use, unit_of_measurement=% @ 2016-12-05T09:05:30.623612-05:00>, 'new_state': <state sensor.ram_use=60.5; icon=mdi:memory, friendly_name=RAM Use, unit_of_measurement=% @ 2016-12-05T09:06:30.638433-05:00>}, 'event_type': 'state_changed', 'time_fired': datetime.datetime(2016, 12, 5, 14, 6, 30, 638663, tzinfo=<UTC>), 'origin': 'LOCAL'}}
16-12-05 09:06:30 homeassistant.components.websocket_api: WS 1829311472: Sending {'id': 2, 'type': 'event', 'event': {'data': {'entity_id': 'sensor.ram_free', 'old_state': <state sensor.ram_free=362.9; icon=mdi:memory, friendly_name=RAM Free, unit_of_measurement=MiB @ 2016-12-05T09:06:00.935431-05:00>, 'new_state': <state sensor.ram_free=364.7; icon=mdi:memory, friendly_name=RAM Free, unit_of_measurement=MiB @ 2016-12-05T09:06:30.951703-05:00>}, 'event_type': 'state_changed', 'time_fired': datetime.datetime(2016, 12, 5, 14, 6, 30, 951938, tzinfo=<UTC>), 'origin': 'LOCAL'}}
16-12-05 09:06:31 homeassistant.components.websocket_api: WS 1829311472: Sending {'id': 2, 'type': 'event', 'event': {'data': {'entity_id': 'sun.sun', 'old_state': <state sun.sun=above_horizon; next_rising=2016-12-06T12:07:44+00:00, azimuth=141.06, next_setting=2016-12-05T21:30:15+00:00, elevation=16.45, friendly_name=Sun Position @ 2016-12-05T09:01:33.461151-05:00>, 'new_state': <state sun.sun=above_horizon; next_rising=2016-12-06T12:07:44+00:00, azimuth=141.26, next_setting=2016-12-05T21:30:15+00:00, elevation=16.57, friendly_name=Sun Position @ 2016-12-05T09:01:33.461151-05:00>}, 'event_type': 'state_changed', 'time_fired': datetime.datetime(2016, 12, 5, 14, 6, 31, 53275, tzinfo=<UTC>), 'origin': 'LOCAL'}}
16-12-05 09:06:31 homeassistant.components.websocket_api: WS 1829311472: Sending {'id': 2, 'type': 'event', 'event': {'data': {'entity_id': 'sensor.since_last_boot', 'old_state': <state sensor.since_last_boot=17:15:26.963997; icon=mdi:clock, friendly_name=Since Last Boot @ 2016-12-05T09:06:01.073894-05:00>, 'new_state': <state sensor.since_last_boot=17:15:56.979571; icon=mdi:clock, friendly_name=Since Last Boot @ 2016-12-05T09:06:31.131436-05:00>}, 'event_type': 'state_changed', 'time_fired': datetime.datetime(2016, 12, 5, 14, 6, 31, 131608, tzinfo=<UTC>), 'origin': 'LOCAL'}}
16-12-05 09:06:31 homeassistant.components.websocket_api: WS 1829311472: Sending {'id': 2, 'type': 'event', 'event': {'data': {'entity_id': 'sensor.cpu_use', 'old_state': <state sensor.cpu_use=8; icon=mdi:memory, friendly_name=CPU Use, unit_of_measurement=% @ 2016-12-05T09:06:01.108264-05:00>, 'new_state': <state sensor.cpu_use=14; icon=mdi:memory, friendly_name=CPU Use, unit_of_measurement=% @ 2016-12-05T09:06:31.234540-05:00>}, 'event_type': 'state_changed', 'time_fired': datetime.datetime(2016, 12, 5, 14, 6, 31, 234701, tzinfo=<UTC>), 'origin': 'LOCAL'}}
16-12-05 09:06:31 homeassistant.components.websocket_api: WS 1829311472: Sending {'id': 2, 'type': 'event', 'event': {'data': {'entity_id': 'sensor.rp1_ram_use', 'old_state': <state sensor.rp1_ram_use=477.1; icon=mdi:dns, friendly_name=Memory Used Percentage, unit_of_measurement=MiB @ 2016-12-05T09:05:01.608669-05:00>, 'new_state': <state sensor.rp1_ram_use=477.3; icon=mdi:dns, friendly_name=Memory Used Percentage, unit_of_measurement=MiB @ 2016-12-05T09:06:31.632178-05:00>}, 'event_type': 'state_changed', 'time_fired': datetime.datetime(2016, 12, 5, 14, 6, 31, 632335, tzinfo=<UTC>), 'origin': 'LOCAL'}}
16-12-05 09:06:31 homeassistant.components.websocket_api: WS 1829311472: Sending {'id': 2, 'type': 'event', 'event': {'data': {'entity_id': 'sensor.rp1_ram_free', 'old_state': <state sensor.rp1_ram_free=448.4; icon=mdi:border-horizontal, friendly_name=Memory Free, unit_of_measurement=MiB @ 2016-12-05T09:05:01.645016-05:00>, 'new_state': <state sensor.rp1_ram_free=448.2; icon=mdi:border-horizontal, friendly_name=Memory Free, unit_of_measurement=MiB @ 2016-12-05T09:06:31.696502-05:00>}, 'event_type': 'state_changed', 'time_fired': datetime.datetime(2016, 12, 5, 14, 6, 31, 696645, tzinfo=<UTC>), 'origin': 'LOCAL'}}
16-12-05 09:06:31 homeassistant.core: Error doing job: Exception in callback _SelectorSslTransport._write_ready()
Traceback (most recent call last):
  File "/usr/lib/python3.4/asyncio/events.py", line 120, in _run
    self._callback(*self._args)
  File "/usr/lib/python3.4/asyncio/selector_events.py", line 877, in _write_ready
    self._loop.remove_writer(self._sock_fd)
  File "/usr/lib/python3.4/asyncio/selector_events.py", line 243, in remove_writer
    self._selector.modify(fd, mask, (reader, None))
  File "/usr/lib/python3.4/selectors.py", line 250, in modify
    key = self.register(fileobj, events, data)
  File "/usr/lib/python3.4/selectors.py", line 393, in register
    key = super().register(fileobj, events, data)
  File "/usr/lib/python3.4/selectors.py", line 230, in register
    .format(fileobj, key.fd))
KeyError: '40 (FD 40) is already registered'
16-12-05 09:06:31 homeassistant.components.websocket_api: WS 1829311472: Sending {'id': 2, 'type': 'event', 'event': {'data': {'entity_id': 'sensor.rp1_cpu_load', 'old_state': <state sensor.rp1_cpu_load=0.75; icon=mdi:flip-to-front, friendly_name=CPU Load, unit_of_measurement=15 min @ 2016-12-05T09:05:01.804757-05:00>, 'new_state': <state sensor.rp1_cpu_load=0.69; icon=mdi:flip-to-front, friendly_name=CPU Load, unit_of_measurement=15 min @ 2016-12-05T09:06:31.928601-05:00>}, 'event_type': 'state_changed', 'time_fired': datetime.datetime(2016, 12, 5, 14, 6, 31, 928779, tzinfo=<UTC>), 'origin': 'LOCAL'}}
16-12-05 09:06:31 homeassistant.components.websocket_api: WS 1829311472: Sending {'id': 2, 'type': 'event', 'event': {'data': {'entity_id': 'sensor.rp1_running', 'old_state': <state sensor.rp1_running=4; icon=mdi:run, friendly_name=Tasks Running/Active, unit_of_measurement=Count @ 2016-12-05T09:05:01.866492-05:00>, 'new_state': <state sensor.rp1_running=1; icon=mdi:run, friendly_name=Tasks Running/Active, unit_of_measurement=Count @ 2016-12-05T09:06:31.968326-05:00>}, 'event_type': 'state_changed', 'time_fired': datetime.datetime(2016, 12, 5, 14, 6, 31, 968484, tzinfo=<UTC>), 'origin': 'LOCAL'}}
16-12-05 09:06:32 homeassistant.components.websocket_api: WS 1829311472: Sending {'id': 2, 'type': 'event', 'event': {'data': {'entity_id': 'sensor.rp1_total', 'old_state': <state sensor.rp1_total=183; icon=mdi:account-multiple, friendly_name=Total Tasks, unit_of_measurement=Count @ 2016-12-05T09:05:01.899148-05:00>, 'new_state': <state sensor.rp1_total=178; icon=mdi:account-multiple, friendly_name=Total Tasks, unit_of_measurement=Count @ 2016-12-05T09:06:32.028455-05:00>}, 'event_type': 'state_changed', 'time_fired': datetime.datetime(2016, 12, 5, 14, 6, 32, 28639, tzinfo=<UTC>), 'origin': 'LOCAL'}}
16-12-05 09:06:32 homeassistant.components.websocket_api: WS 1829311472: Sending {'id': 2, 'type': 'event', 'event': {'data': {'entity_id': 'sensor.rp1_thread', 'old_state': <state sensor.rp1_thread=295; icon=mdi:animation, friendly_name=Threads in Use, unit_of_measurement=Count @ 2016-12-05T09:05:01.922673-05:00>, 'new_state': <state sensor.rp1_thread=291; icon=mdi:animation, friendly_name=Threads in Use, unit_of_measurement=Count @ 2016-12-05T09:06:32.053617-05:00>}, 'event_type': 'state_changed', 'time_fired': datetime.datetime(2016, 12, 5, 14, 6, 32, 53793, tzinfo=<UTC>), 'origin': 'LOCAL'}}
16-12-05 09:06:32 homeassistant.components.websocket_api: WS 1829311472: Sending {'id': 2, 'type': 'event', 'event': {'data': {'entity_id': 'sensor.rp1_sleeping', 'old_state': <state sensor.rp1_sleeping=179; icon=mdi:hotel, friendly_name=Tasks Sleeping/Inactive, unit_of_measurement=Count @ 2016-12-05T09:03:34.048670-05:00>, 'new_state': <state sensor.rp1_sleeping=177; icon=mdi:hotel, friendly_name=Tasks Sleeping/Inactive, unit_of_measurement=Count @ 2016-12-05T09:06:32.084404-05:00>}, 'event_type': 'state_changed', 'time_fired': datetime.datetime(2016, 12, 5, 14, 6, 32, 84558, tzinfo=<UTC>), 'origin': 'LOCAL'}}
16-12-05 09:06:32 homeassistant.components.websocket_api: WS 1829311472: Sending {'id': 2, 'type': 'event', 'event': {'data': {'entity_id': 'sensor.rp1_ram_use_2', 'old_state': <state sensor.rp1_ram_use_2=51.5; icon=mdi:dns, friendly_name=Memory Used, unit_of_measurement=% @ 2016-12-05T09:05:02.083217-05:00>, 'new_state': <state sensor.rp1_ram_use_2=51.6; icon=mdi:dns, friendly_name=Memory Used, unit_of_measurement=% @ 2016-12-05T09:06:32.119873-05:00>}, 'event_type': 'state_changed', 'time_fired': datetime.datetime(2016, 12, 5, 14, 6, 32, 120036, tzinfo=<UTC>), 'origin': 'LOCAL'}}
16-12-05 09:07:00 homeassistant.components.websocket_api: WS 1829311472: Sending {'id': 2, 'type': 'event', 'event': {'data': {'entity_id': 'sensor.ram_use', 'old_state': <state sensor.ram_use=60.5; icon=mdi:memory, friendly_name=RAM Use, unit_of_measurement=% @ 2016-12-05T09:06:30.638433-05:00>, 'new_state': <state sensor.ram_use=61.4; icon=mdi:memory, friendly_name=RAM Use, unit_of_measurement=% @ 2016-12-05T09:07:00.641494-05:00>}, 'event_type': 'state_changed', 'time_fired': datetime.datetime(2016, 12, 5, 14, 7, 0, 641725, tzinfo=<UTC>), 'origin': 'LOCAL'}}
16-12-05 09:07:00 homeassistant.components.websocket_api: WS 1829311472: Sending {'id': 2, 'type': 'event', 'event': {'data': {'entity_id': 'sensor.ram_free', 'old_state': <state sensor.ram_free=364.7; icon=mdi:memory, friendly_name=RAM Free, unit_of_measurement=MiB @ 2016-12-05T09:06:30.951703-05:00>, 'new_state': <state sensor.ram_free=353.5; icon=mdi:memory, friendly_name=RAM Free, unit_of_measurement=MiB @ 2016-12-05T09:07:00.822315-05:00>}, 'event_type': 'state_changed', 'time_fired': datetime.datetime(2016, 12, 5, 14, 7, 0, 822516, tzinfo=<UTC>), 'origin': 'LOCAL'}}
16-12-05 09:07:00 homeassistant.components.websocket_api: WS 1829311472: Sending {'id': 2, 'type': 'event', 'event': {'data': {'entity_id': 'sensor.since_last_boot', 'old_state': <state sensor.since_last_boot=17:15:56.979571; icon=mdi:clock, friendly_name=Since Last Boot @ 2016-12-05T09:06:31.131436-05:00>, 'new_state': <state sensor.since_last_boot=17:16:26.866559; icon=mdi:clock, friendly_name=Since Last Boot @ 2016-12-05T09:07:00.969021-05:00>}, 'event_type': 'state_changed', 'time_fired': datetime.datetime(2016, 12, 5, 14, 7, 0, 969228, tzinfo=<UTC>), 'origin': 'LOCAL'}}
16-12-05 09:07:01 homeassistant.components.websocket_api: WS 1829311472: Sending {'id': 2, 'type': 'event', 'event': {'data': {'entity_id': 'sensor.cpu_use', 'old_state': <state sensor.cpu_use=14; icon=mdi:memory, friendly_name=CPU Use, unit_of_measurement=% @ 2016-12-05T09:06:31.234540-05:00>, 'new_state': <state sensor.cpu_use=12; icon=mdi:memory, friendly_name=CPU Use, unit_of_measurement=% @ 2016-12-05T09:07:01.005642-05:00>}, 'event_type': 'state_changed', 'time_fired': datetime.datetime(2016, 12, 5, 14, 7, 1, 5815, tzinfo=<UTC>), 'origin': 'LOCAL'}}
16-12-05 09:07:30 homeassistant.components.websocket_api: WS 1829311472: Sending {'id': 2, 'type': 'event', 'event': {'data': {'entity_id': 'sensor.ram_use', 'old_state': <state sensor.ram_use=61.4; icon=mdi:memory, friendly_name=RAM Use, unit_of_measurement=% @ 2016-12-05T09:07:00.641494-05:00>, 'new_state': <state sensor.ram_use=61.5; icon=mdi:memory, friendly_name=RAM Use, unit_of_measurement=% @ 2016-12-05T09:07:30.593210-05:00>}, 'event_type': 'state_changed', 'time_fired': datetime.datetime(2016, 12, 5, 14, 7, 30, 593375, tzinfo=<UTC>), 'origin': 'LOCAL'}}
16-12-05 09:07:30 homeassistant.components.websocket_api: WS 1829311472: Sending {'id': 2, 'type': 'event', 'event': {'data': {'entity_id': 'sun.sun', 'old_state': <state sun.sun=above_horizon; next_rising=2016-12-06T12:07:44+00:00, azimuth=141.26, next_setting=2016-12-05T21:30:15+00:00, elevation=16.57, friendly_name=Sun Position @ 2016-12-05T09:01:33.461151-05:00>, 'new_state': <state sun.sun=above_horizon; next_rising=2016-12-06T12:07:44+00:00, azimuth=141.47, next_setting=2016-12-05T21:30:15+00:00, elevation=16.69, friendly_name=Sun Position @ 2016-12-05T09:01:33.461151-05:00>}, 'event_type': 'state_changed', 'time_fired': datetime.datetime(2016, 12, 5, 14, 7, 30, 826217, tzinfo=<UTC>), 'origin': 'LOCAL'}}
16-12-05 09:07:31 homeassistant.components.websocket_api: WS 1829311472: Sending {'id': 2, 'type': 'event', 'event': {'data': {'entity_id': 'sensor.ram_free', 'old_state': <state sensor.ram_free=353.5; icon=mdi:memory, friendly_name=RAM Free, unit_of_measurement=MiB @ 2016-12-05T09:07:00.822315-05:00>, 'new_state': <state sensor.ram_free=355.7; icon=mdi:memory, friendly_name=RAM Free, unit_of_measurement=MiB @ 2016-12-05T09:07:30.915416-05:00>}, 'event_type': 'state_changed', 'time_fired': datetime.datetime(2016, 12, 5, 14, 7, 30, 915902, tzinfo=<UTC>), 'origin': 'LOCAL'}}
16-12-05 09:07:31 homeassistant.components.websocket_api: WS 1829311472: Sending {'id': 2, 'type': 'event', 'event': {'data': {'entity_id': 'sensor.since_last_boot', 'old_state': <state sensor.since_last_boot=17:16:26.866559; icon=mdi:clock, friendly_name=Since Last Boot @ 2016-12-05T09:07:00.969021-05:00>, 'new_state': <state sensor.since_last_boot=17:16:57.046231; icon=mdi:clock, friendly_name=Since Last Boot @ 2016-12-05T09:07:31.171394-05:00>}, 'event_type': 'state_changed', 'time_fired': datetime.datetime(2016, 12, 5, 14, 7, 31, 171549, tzinfo=<UTC>), 'origin': 'LOCAL'}}
16-12-05 09:07:31 homeassistant.components.websocket_api: WS 1829311472: Sending {'id': 2, 'type': 'event', 'event': {'data': {'entity_id': 'sensor.cpu_use', 'old_state': <state sensor.cpu_use=12; icon=mdi:memory, friendly_name=CPU Use, unit_of_measurement=% @ 2016-12-05T09:07:01.005642-05:00>, 'new_state': <state sensor.cpu_use=14; icon=mdi:memory, friendly_name=CPU Use, unit_of_measurement=% @ 2016-12-05T09:07:31.257369-05:00>}, 'event_type': 'state_changed', 'time_fired': datetime.datetime(2016, 12, 5, 14, 7, 31, 257568, tzinfo=<UTC>), 'origin': 'LOCAL'}}
rpitera commented 7 years ago

Just for the sake of additional info, I am not using NGINX or any other proxy.

jvrproductions commented 7 years ago

Hello this is the new error after activate the websocket_api / Its a brand new installation (I mess my old one trying to downgrade to 33) so far only have nest and hue. As soon i pur wink, all go down. Any way this are the errors with no wink just hue and nest

` 16-12-05 14:11:53 homeassistant.core: Bus:Handling <Event component_loaded[L]: component=logger> 16-12-05 14:11:53 homeassistant.bootstrap: Setting up http 16-12-05 14:11:53 homeassistant.core: Bus:Handling <Event component_loaded[L]: component=http> 16-12-05 14:11:53 homeassistant.bootstrap: Setting up nest 16-12-05 14:11:53 homeassistant.components.nest: proceeding with setup 16-12-05 14:11:54 homeassistant.components.nest: Structures to include: ['Home'] 16-12-05 14:11:54 homeassistant.components.nest: proceeding with discovery 16-12-05 14:11:54 homeassistant.components.nest: setup done 16-12-05 14:11:54 homeassistant.core: Bus:Handling <Event component_loaded[L]: component=nest> 16-12-05 14:11:54 homeassistant.bootstrap: Setting up updater 16-12-05 14:11:54 homeassistant.core: Bus:Handling <Event component_loaded[L]: component=updater> 16-12-05 14:11:54 homeassistant.bootstrap: Setting up api 16-12-05 14:11:54 homeassistant.core: Bus:Handling <Event component_loaded[L]: component=api> 16-12-05 14:11:54 homeassistant.bootstrap: Setting up websocket_api 16-12-05 14:11:54 homeassistant.core: Bus:Handling <Event component_loaded[L]: component=websocket_api> 16-12-05 14:11:54 homeassistant.bootstrap: Setting up frontend 16-12-05 14:11:54 homeassistant.core: Bus:Handling <Event component_loaded[L]: component=frontend> 16-12-05 14:11:54 homeassistant.bootstrap: Setting up logbook 16-12-05 14:11:54 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=logbook, service=log> 16-12-05 14:11:54 homeassistant.core: Bus:Handling <Event component_loaded[L]: component=logbook> 16-12-05 14:11:54 homeassistant.bootstrap: Setting up conversation 16-12-05 14:11:54 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=conversation, service=process> 16-12-05 14:11:54 homeassistant.core: Bus:Handling <Event component_loaded[L]: component=conversation> 16-12-05 14:11:54 homeassistant.bootstrap: Setting up history 16-12-05 14:11:54 homeassistant.core: Bus:Handling <Event component_loaded[L]: component=history> 16-12-05 14:11:54 homeassistant.loader: Loaded sensor.yr from homeassistant.components.sensor.yr 16-12-05 14:11:54 homeassistant.bootstrap: Setting up sensor 16-12-05 14:11:54 homeassistant.components.sensor: Setting up sensor.yr 16-12-05 14:11:54 homeassistant.core: Bus:Handling <Event state_changed[L]: old_state=None, new_state=<state sensor.yr_symbol=unknown; friendly_name=yr Symbol, entity_picture=//api.met.no/weatherapi/weathericon/1.1/?symbol=None;content_type=image/png, attribution=Weather forecast from yr.no, delivered by the Norwegian Meteorological Institute and the NRK. @ 2016-12-05T09:11:54.991223-05:00>, entity_id=sensor.yr_symbol> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event state_changed[L]: old_state=<state sensor.yr_symbol=unknown; friendly_name=yr Symbol, entity_picture=//api.met.no/weatherapi/weathericon/1.1/?symbol=None;content_type=image/png, attribution=Weather forecast from yr.no, delivered by the Norwegian Meteorological Institute and the NRK. @ 2016-12-05T09:11:54.991223-05:00>, new_state=<state sensor.yr_symbol=4; friendly_name=yr Symbol, entity_picture=//api.met.no/weatherapi/weathericon/1.1/?symbol=4;content_type=image/png, attribution=Weather forecast from yr.no, delivered by the Norwegian Meteorological Institute and the NRK. @ 2016-12-05T09:11:56.169778-05:00>, entity_id=sensor.yr_symbol> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event component_loaded[L]: component=sensor> 16-12-05 14:11:56 homeassistant.loader: Loaded climate.nest from homeassistant.components.climate.nest 16-12-05 14:11:56 homeassistant.bootstrap: Component nest already set up. 16-12-05 14:11:56 homeassistant.bootstrap: Setting up climate 16-12-05 14:11:56 homeassistant.bootstrap: Component nest already set up. 16-12-05 14:11:56 homeassistant.components.climate: Setting up climate.nest 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=climate, service=set_away_mode> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=climate, service=set_aux_heat> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=climate, service=set_temperature> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=climate, service=set_humidity> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=climate, service=set_fan_mode> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=climate, service=set_operation_mode> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=climate, service=set_swing_mode> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event component_loaded[L]: component=climate> 16-12-05 14:11:56 homeassistant.bootstrap: Setting up discovery 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event component_loaded[L]: component=discovery> 16-12-05 14:11:56 homeassistant.bootstrap: Setting up sun 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event state_changed[L]: old_state=None, new_state=<state sun.sun=above_horizon; friendly_name=Sun, next_setting=2016-12-05T22:28:47+00:00, next_rising=2016-12-06T12:04:33+00:00, azimuth=0, elevation=0 @ 2016-12-05T09:11:56.476740-05:00>, entity_id=sun.sun> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event component_loaded[L]: component=sun> 16-12-05 14:11:56 homeassistant.loader: Loaded light.hue from homeassistant.components.light.hue 16-12-05 14:11:56 homeassistant.bootstrap: Setting up light 16-12-05 14:11:56 homeassistant.components.light: Setting up light.hue 16-12-05 14:11:56 phue: Attempting to connect to the bridge... 16-12-05 14:11:56 phue: Using ip: 192.168.1.49 16-12-05 14:11:56 phue: Using username from config: UuJqs2FXkpUeelWxz8zlJswxfgZ0AwoMcdeXq-IQ 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=light, service=hue_activate_scene> 16-12-05 14:11:56 phue: GET /api/UuJqs2FXkpUeelWxz8zlJswxfgZ0AwoMcdeXq-IQ None 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event state_changed[L]: old_state=None, new_state=<state light.led_strip=off; supported_features=127, friendly_name=Led strip @ 2016-12-05T09:11:56.708735-05:00>, entity_id=light.led_strip> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event state_changed[L]: old_state=None, new_state=<state light.lampara_sala=off; supported_features=127, friendly_name=Lampara sala @ 2016-12-05T09:11:56.712545-05:00>, entity_id=light.lampara_sala> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event state_changed[L]: old_state=None, new_state=<state light.cocina_1=on; supported_features=127, friendly_name=Cocina 1, color_temp=369, rgb_color=(255, 199, 116), brightness=254, xy_color=[0.4596, 0.4105] @ 2016-12-05T09:11:56.714820-05:00>, entity_id=light.cocina_1> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event state_changed[L]: old_state=None, new_state=<state light.bolita=off; supported_features=127, friendly_name=Bolita @ 2016-12-05T09:11:56.718005-05:00>, entity_id=light.bolita> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event state_changed[L]: old_state=None, new_state=<state light.cocina_3=on; supported_features=127, friendly_name=Cocina 3, color_temp=369, rgb_color=(255, 199, 116), brightness=254, xy_color=[0.4596, 0.4105] @ 2016-12-05T09:11:56.721785-05:00>, entity_id=light.cocina_3> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event state_changed[L]: old_state=None, new_state=<state light.escalera1=off; supported_features=127, friendly_name=Escalera1 @ 2016-12-05T09:11:56.725371-05:00>, entity_id=light.escalera1> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event state_changed[L]: old_state=None, new_state=<state light.jose_mesa_noche=off; supported_features=127, friendly_name=Jose mesa noche @ 2016-12-05T09:11:56.729516-05:00>, entity_id=light.jose_mesa_noche> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event state_changed[L]: old_state=None, new_state=<state light.ale_mesa_noche=off; supported_features=127, friendly_name=Ale mesa noche @ 2016-12-05T09:11:56.735226-05:00>, entity_id=light.ale_mesa_noche> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event state_changed[L]: old_state=None, new_state=<state light.escalera2=off; supported_features=127, friendly_name=Escalera2 @ 2016-12-05T09:11:56.738484-05:00>, entity_id=light.escalera2> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event state_changed[L]: old_state=None, new_state=<state light.cocina4=on; supported_features=127, friendly_name=Cocina4, color_temp=369, rgb_color=(255, 199, 116), brightness=254, xy_color=[0.4596, 0.4105] @ 2016-12-05T09:11:56.741344-05:00>, entity_id=light.cocina4> 16-12-05 14:11:56 homeassistant.loader: Loaded group from homeassistant.components.group 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event state_changed[L]: old_state=None, new_state=<state group.all_lights=on; friendly_name=all lights, hidden=True, entity_id=('light.led_strip', 'light.bolita', 'light.cocina4', 'light.cocina_1', 'light.escalera1', 'light.cocina_3', 'light.ale_mesa_noche', 'light.jose_mesa_noche', 'light.escalera2', 'light.lampara_sala'), order=0, auto=True @ 2016-12-05T09:11:56.746468-05:00>, entity_id=group.all_lights> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=light, service=turn_on> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=light, service=turn_off> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=light, service=toggle> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event component_loaded[L]: component=light> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event platform_discovered[L]: discovered=, platform=nest, service=load_platform.climate> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event platform_discovered[L]: discovered=, platform=nest, service=load_platform.sensor> 16-12-05 14:11:56 homeassistant.loader: Loaded binary_sensor from homeassistant.components.binary_sensor 16-12-05 14:11:56 homeassistant.components.climate: Setting up climate.nest 16-12-05 14:11:56 homeassistant.components.climate.nest: Setting up nest thermostat 16-12-05 14:11:56 homeassistant.loader: Loaded sensor.nest from homeassistant.components.sensor.nest 16-12-05 14:11:56 homeassistant.bootstrap: Component nest already set up. 16-12-05 14:11:56 homeassistant.bootstrap: Setting up binary_sensor 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event component_loaded[L]: component=binary_sensor> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event platform_discovered[L]: discovered=, platform=nest, service=load_platform.binary_sensor> 16-12-05 14:11:56 homeassistant.loader: Loaded camera from homeassistant.components.camera 16-12-05 14:11:56 homeassistant.components.sensor: Setting up sensor.nest 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event state_changed[L]: old_state=None, new_state=<state climate.upstairs_upstairs=cool; current_temperature=75, max_temp=None, fan_mode=auto, min_temp=None, operation_list=['off', 'heat', 'cool', 'auto', 'eco'], operation_mode=cool, friendly_name=Upstairs (Upstairs), unit_of_measurement=°F, fan_list=['on', 'auto'], temperature=76, away_mode=off @ 2016-12-05T09:11:56.891804-05:00>, entity_id=climate.upstairs_upstairs> 16-12-05 14:11:56 homeassistant.loader: Loaded binary_sensor.nest from homeassistant.components.binary_sensor.nest 16-12-05 14:11:56 homeassistant.bootstrap: Component nest already set up. 16-12-05 14:11:56 homeassistant.bootstrap: Setting up camera 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event component_loaded[L]: component=camera> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event platform_discovered[L]: discovered=, platform=nest, service=load_platform.camera> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event state_changed[L]: old_state=None, new_state=<state sensor.upstairs_thermostat_upstairs_humidity=35; friendly_name=Upstairs Thermostat (Upstairs) humidity, unit_of_measurement=% @ 2016-12-05T09:11:56.910392-05:00>, entity_id=sensor.upstairs_thermostat_upstairs_humidity> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event state_changed[L]: old_state=None, new_state=<state sensor.upstairs_thermostat_upstairs_operation_mode=cool; friendly_name=Upstairs Thermostat (Upstairs) operation mode @ 2016-12-05T09:11:56.912798-05:00>, entity_id=sensor.upstairs_thermostat_upstairs_operation_mode> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event state_changed[L]: old_state=None, new_state=<state sensor.upstairs_thermostat_upstairs_temperature=75; friendly_name=Upstairs Thermostat (Upstairs) temperature, unit_of_measurement=°F @ 2016-12-05T09:11:56.914894-05:00>, entity_id=sensor.upstairs_thermostat_upstairs_temperature> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event state_changed[L]: old_state=None, new_state=<state sensor.upstairs_thermostat_upstairs_target=76; friendly_name=Upstairs Thermostat (Upstairs) target, unit_of_measurement=°F @ 2016-12-05T09:11:56.917048-05:00>, entity_id=sensor.upstairs_thermostat_upstairs_target> 16-12-05 14:11:56 homeassistant.components.binary_sensor: Setting up binary_sensor.nest 16-12-05 14:11:56 homeassistant.loader: Loaded camera.nest from homeassistant.components.camera.nest 16-12-05 14:11:56 homeassistant.bootstrap: Component nest already set up. 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event state_changed[L]: old_state=None, new_state=<state binary_sensor.upstairs_thermostat_upstairs_has_leaf=off; friendly_name=Upstairs Thermostat (Upstairs) has leaf @ 2016-12-05T09:11:56.929977-05:00>, entity_id=binary_sensor.upstairs_thermostat_upstairs_has_leaf> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event state_changed[L]: old_state=None, new_state=<state binary_sensor.upstairs_thermostat_upstairs_online=off; friendly_name=Upstairs Thermostat (Upstairs) online @ 2016-12-05T09:11:56.933032-05:00>, entity_id=binary_sensor.upstairs_thermostat_upstairs_online> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event state_changed[L]: old_state=None, new_state=<state binary_sensor.upstairs_thermostat_upstairs_fan=off; friendly_name=Upstairs Thermostat (Upstairs) fan @ 2016-12-05T09:11:56.935064-05:00>, entity_id=binary_sensor.upstairs_thermostat_upstairs_fan> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event state_changed[L]: old_state=None, new_state=<state binary_sensor.upstairs_thermostat_upstairs_is_using_emergency_heat=off; friendly_name=Upstairs Thermostat (Upstairs) is using emergency heat @ 2016-12-05T09:11:56.937315-05:00>, entity_id=binary_sensor.upstairs_thermostat_upstairs_is_using_emergency_heat> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event state_changed[L]: old_state=None, new_state=<state binary_sensor.upstairs_thermostat_upstairs_is_locked=off; friendly_name=Upstairs Thermostat (Upstairs) is locked @ 2016-12-05T09:11:56.939349-05:00>, entity_id=binary_sensor.upstairs_thermostat_upstairs_is_locked> 16-12-05 14:11:56 homeassistant.components.camera: Setting up camera.nest 16-12-05 14:11:56 homeassistant.core: Starting Home Assistant core loop 16-12-05 14:11:56 homeassistant.core: Starting Home Assistant 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=homeassistant, service=stop> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=homeassistant, service=restart> 16-12-05 14:11:56 homeassistant.core: Bus:Handling <Event homeassistant_start[L]> 16-12-05 14:11:56 homeassistant.core: Timer:starting 16-12-05 14:11:57 netdisco.service: Scanning 16-12-05 14:12:00 phue: GET /api/UuJqs2FXkpUeelWxz8zlJswxfgZ0AwoMcdeXq-IQ None 16-12-05 14:12:01 homeassistant.components.http: Serving /api/websocket to 192.168.1.210 (auth: True) 16-12-05 14:12:01 homeassistant.components.websocket_api: WS 1770536464: Connected 16-12-05 14:12:01 homeassistant.components.websocket_api: WS 1770536464: Sending {'ha_version': '0.34.2', 'type': 'auth_ok'} 16-12-05 14:12:01 homeassistant.components.websocket_api: WS 1770536464: Received {'id': 2, 'type': 'subscribe_events', 'event_type': 'state_changed'} 16-12-05 14:12:01 homeassistant.components.websocket_api: WS 1770536464: Sending {'id': 2, 'result': None, 'type': 'result', 'success': True} 16-12-05 14:12:01 homeassistant.components.websocket_api: WS 1770536464: Received {'id': 3, 'type': 'subscribe_events', 'event_type': 'component_loaded'} 16-12-05 14:12:01 homeassistant.components.websocket_api: WS 1770536464: Sending {'id': 3, 'result': None, 'type': 'result', 'success': True} 16-12-05 14:12:01 homeassistant.components.websocket_api: WS 1770536464: Received {'id': 4, 'type': 'subscribe_events', 'event_type': 'service_registered'} 16-12-05 14:12:01 homeassistant.components.websocket_api: WS 1770536464: Sending {'id': 4, 'result': None, 'type': 'result', 'success': True} 16-12-05 14:12:01 homeassistant.components.http: Serving /api/bootstrap to 192.168.1.210 (auth: True) 16-12-05 14:12:23 netdisco.ssdp: Error fetching description at https://192.168.1.107:8888 16-12-05 14:12:24 homeassistant.components.discovery: Found new service: mystrom ('BSB002', 'http://Philips-hue.local.', '00:17:88:21:1A:36') 16-12-05 14:12:24 homeassistant.components.discovery: Found new service: google_cast ('192.168.1.123', 8009) 16-12-05 14:12:24 homeassistant.components.discovery: Found new service: philips_hue ('Philips hue (192.168.1.49)', 'http://192.168.1.49:80/') 16-12-05 14:12:24 homeassistant.loader: Loaded media_player from homeassistant.components.media_player 16-12-05 14:12:24 homeassistant.components.discovery: Found new service: homekit {'id': '00:17:88:21:1A:36', 's#': '37', 'md': 'BSB002', 'host': 'http://Philips-hue.local.', 'sf': '0', 'pv': '1.0', 'c#': '13', 'ci': '2', 'ff': '1'} 16-12-05 14:12:24 homeassistant.bootstrap: Setting up media_player 16-12-05 14:12:25 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=media_player, service=media_pause> 16-12-05 14:12:25 homeassistant.components.websocket_api: WS 1770536464: Sending {'id': 4, 'type': 'event', 'event': {'origin': 'LOCAL', 'data': {'domain': 'media_player', 'service': 'media_pause'}, 'event_type': 'service_registered', 'time_fired': datetime.datetime(2016, 12, 5, 14, 12, 25, 174301, tzinfo=)}} 16-12-05 14:12:25 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=media_player, service=volume_down> 16-12-05 14:12:25 homeassistant.components.websocket_api: WS 1770536464: Sending {'id': 4, 'type': 'event', 'event': {'origin': 'LOCAL', 'data': {'domain': 'media_player', 'service': 'volume_down'}, 'event_type': 'service_registered', 'time_fired': datetime.datetime(2016, 12, 5, 14, 12, 25, 180546, tzinfo=)}} 16-12-05 14:12:25 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=media_player, service=media_stop> 16-12-05 14:12:25 homeassistant.components.websocket_api: WS 1770536464: Sending {'id': 4, 'type': 'event', 'event': {'origin': 'LOCAL', 'data': {'domain': 'media_player', 'service': 'media_stop'}, 'event_type': 'service_registered', 'time_fired': datetime.datetime(2016, 12, 5, 14, 12, 25, 186208, tzinfo=)}} 16-12-05 14:12:25 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=media_player, service=turn_on> 16-12-05 14:12:25 homeassistant.components.websocket_api: WS 1770536464: Sending {'id': 4, 'type': 'event', 'event': {'origin': 'LOCAL', 'data': {'domain': 'media_player', 'service': 'turn_on'}, 'event_type': 'service_registered', 'time_fired': datetime.datetime(2016, 12, 5, 14, 12, 25, 193216, tzinfo=)}} 16-12-05 14:12:25 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=media_player, service=media_next_track> 16-12-05 14:12:25 homeassistant.components.websocket_api: WS 1770536464: Sending {'id': 4, 'type': 'event', 'event': {'origin': 'LOCAL', 'data': {'domain': 'media_player', 'service': 'media_next_track'}, 'event_type': 'service_registered', 'time_fired': datetime.datetime(2016, 12, 5, 14, 12, 25, 196666, tzinfo=)}} 16-12-05 14:12:25 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=media_player, service=media_play> 16-12-05 14:12:25 homeassistant.components.websocket_api: WS 1770536464: Sending {'id': 4, 'type': 'event', 'event': {'origin': 'LOCAL', 'data': {'domain': 'media_player', 'service': 'media_play'}, 'event_type': 'service_registered', 'time_fired': datetime.datetime(2016, 12, 5, 14, 12, 25, 205484, tzinfo=)}} 16-12-05 14:12:25 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=media_player, service=media_play_pause> 16-12-05 14:12:25 homeassistant.components.websocket_api: WS 1770536464: Sending {'id': 4, 'type': 'event', 'event': {'origin': 'LOCAL', 'data': {'domain': 'media_player', 'service': 'media_play_pause'}, 'event_type': 'service_registered', 'time_fired': datetime.datetime(2016, 12, 5, 14, 12, 25, 214344, tzinfo=)}} 16-12-05 14:12:25 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=media_player, service=volume_up> 16-12-05 14:12:25 homeassistant.components.websocket_api: WS 1770536464: Sending {'id': 4, 'type': 'event', 'event': {'origin': 'LOCAL', 'data': {'domain': 'media_player', 'service': 'volume_up'}, 'event_type': 'service_registered', 'time_fired': datetime.datetime(2016, 12, 5, 14, 12, 25, 219521, tzinfo=)}} 16-12-05 14:12:25 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=media_player, service=media_previous_track> 16-12-05 14:12:25 homeassistant.components.websocket_api: WS 1770536464: Sending {'id': 4, 'type': 'event', 'event': {'origin': 'LOCAL', 'data': {'domain': 'media_player', 'service': 'media_previous_track'}, 'event_type': 'service_registered', 'time_fired': datetime.datetime(2016, 12, 5, 14, 12, 25, 224649, tzinfo=)}} 16-12-05 14:12:25 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=media_player, service=toggle> 16-12-05 14:12:25 homeassistant.components.websocket_api: WS 1770536464: Sending {'id': 4, 'type': 'event', 'event': {'origin': 'LOCAL', 'data': {'domain': 'media_player', 'service': 'toggle'}, 'event_type': 'service_registered', 'time_fired': datetime.datetime(2016, 12, 5, 14, 12, 25, 233008, tzinfo=)}} 16-12-05 14:12:25 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=media_player, service=turn_off> 16-12-05 14:12:25 homeassistant.components.websocket_api: WS 1770536464: Sending {'id': 4, 'type': 'event', 'event': {'origin': 'LOCAL', 'data': {'domain': 'media_player', 'service': 'turn_off'}, 'event_type': 'service_registered', 'time_fired': datetime.datetime(2016, 12, 5, 14, 12, 25, 236814, tzinfo=)}} 16-12-05 14:12:25 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=media_player, service=clear_playlist> 16-12-05 14:12:25 homeassistant.components.websocket_api: WS 1770536464: Sending {'id': 4, 'type': 'event', 'event': {'origin': 'LOCAL', 'data': {'domain': 'media_player', 'service': 'clear_playlist'}, 'event_type': 'service_registered', 'time_fired': datetime.datetime(2016, 12, 5, 14, 12, 25, 240690, tzinfo=)}} 16-12-05 14:12:25 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=media_player, service=volume_set> 16-12-05 14:12:25 homeassistant.components.websocket_api: WS 1770536464: Sending {'id': 4, 'type': 'event', 'event': {'origin': 'LOCAL', 'data': {'domain': 'media_player', 'service': 'volume_set'}, 'event_type': 'service_registered', 'time_fired': datetime.datetime(2016, 12, 5, 14, 12, 25, 244333, tzinfo=)}} 16-12-05 14:12:25 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=media_player, service=volume_mute> 16-12-05 14:12:25 homeassistant.components.websocket_api: WS 1770536464: Sending {'id': 4, 'type': 'event', 'event': {'origin': 'LOCAL', 'data': {'domain': 'media_player', 'service': 'volume_mute'}, 'event_type': 'service_registered', 'time_fired': datetime.datetime(2016, 12, 5, 14, 12, 25, 247540, tzinfo=)}} 16-12-05 14:12:25 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=media_player, service=media_seek> 16-12-05 14:12:25 homeassistant.components.websocket_api: WS 1770536464: Sending {'id': 4, 'type': 'event', 'event': {'origin': 'LOCAL', 'data': {'domain': 'media_player', 'service': 'media_seek'}, 'event_type': 'service_registered', 'time_fired': datetime.datetime(2016, 12, 5, 14, 12, 25, 262795, tzinfo=)}} 16-12-05 14:12:25 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=media_player, service=select_source> 16-12-05 14:12:25 homeassistant.components.websocket_api: WS 1770536464: Sending {'id': 4, 'type': 'event', 'event': {'origin': 'LOCAL', 'data': {'domain': 'media_player', 'service': 'select_source'}, 'event_type': 'service_registered', 'time_fired': datetime.datetime(2016, 12, 5, 14, 12, 25, 266453, tzinfo=)}} 16-12-05 14:12:25 homeassistant.core: Bus:Handling <Event service_registered[L]: domain=media_player, service=play_media> 16-12-05 14:12:25 homeassistant.components.websocket_api: WS 1770536464: Sending {'id': 4, 'type': 'event', 'event': {'origin': 'LOCAL', 'data': {'domain': 'media_player', 'service': 'play_media'}, 'event_type': 'service_registered', 'time_fired': datetime.datetime(2016, 12, 5, 14, 12, 25, 274380, tzinfo=)}} 16-12-05 14:12:25 homeassistant.core: Bus:Handling <Event component_loaded[L]: component=media_player> 16-12-05 14:12:25 homeassistant.core: Bus:Handling <Event platform_discovered[L]: discovered=('192.168.1.123', 8009), platform=cast, service=load_platform.media_player> 16-12-05 14:12:25 homeassistant.components.websocket_api: WS 1770536464: Sending {'id': 3, 'type': 'event', 'event': {'origin': 'LOCAL', 'data': {'component': 'media_player'}, 'event_type': 'component_loaded', 'time_fired': datetime.datetime(2016, 12, 5, 14, 12, 25, 281916, tzinfo=)}} 16-12-05 14:12:25 homeassistant.core: Bus:Handling <Event platform_discovered[L]: discovered=('Philips hue (192.168.1.49)', 'http://192.168.1.49:80/'), platform=hue, service=load_platform.light> 16-12-05 14:12:25 homeassistant.loader: Loaded media_player.cast from homeassistant.components.media_player.cast 16-12-05 14:12:25 homeassistant.components.light: Setting up light.hue 16-12-05 14:12:25 homeassistant.components.media_player: Setting up media_player.cast 16-12-05 14:12:30 phue: GET /api/UuJqs2FXkpUeelWxz8zlJswxfgZ0AwoMcdeXq-IQ None 16-12-05 14:12:30 homeassistant.core: Bus:Handling <Event state_changed[L]: old_state=<state sun.sun=above_horizon; friendly_name=Sun, next_setting=2016-12-05T22:28:47+00:00, next_rising=2016-12-06T12:04:33+00:00, azimuth=0, elevation=0 @ 2016-12-05T09:11:56.476740-05:00>, new_state=<state sun.sun=above_horizon; friendly_name=Sun, next_setting=2016-12-05T22:28:47+00:00, next_rising=2016-12-06T12:04:33+00:00, azimuth=134.03, elevation=22.63 @ 2016-12-05T09:11:56.476740-05:00>, entity_id=sun.sun> 16-12-05 14:12:30 homeassistant.components.websocket_api: WS 1770536464: Sending {'id': 2, 'type': 'event', 'event': {'origin': 'LOCAL', 'data': {'old_state': <state sun.sun=above_horizon; friendly_name=Sun, next_setting=2016-12-05T22:28:47+00:00, next_rising=2016-12-06T12:04:33+00:00, azimuth=0, elevation=0 @ 2016-12-05T09:11:56.476740-05:00>, 'new_state': <state sun.sun=above_horizon; friendly_name=Sun, next_setting=2016-12-05T22:28:47+00:00, next_rising=2016-12-06T12:04:33+00:00, azimuth=134.03, elevation=22.63 @ 2016-12-05T09:11:56.476740-05:00>, 'entity_id': 'sun.sun'}, 'event_type': 'state_changed', 'time_fired': datetime.datetime(2016, 12, 5, 14, 12, 30, 690493, tzinfo=)}} 16-12-05 14:12:33 homeassistant.components.websocket_api: WS 1770536464: Received {'id': 5, 'type': 'ping'} 16-12-05 14:12:33 homeassistant.components.websocket_api: WS 1770536464: Sending {'id': 5, 'type': 'pong'} 16-12-05 14:12:34 pychromecast: Querying device status 16-12-05 14:12:34 pychromecast.controllers: Receiver:Updating status 16-12-05 14:12:34 pychromecast.socket_client: Sending: Message urn:x-cast:com.google.cast.tp.connection from sender-0 to receiver-0: {'senderInfo': {'browserVersion': '44.0.2403.30', 'version': '15.605.1.3', 'sdkType': 2, 'systemVersion': 'Macintosh; Intel Mac OS X10_10_3', 'connectionType': 1, 'platform': 4}, 'origin': {}, 'type': 'CONNECT', 'userAgent': 'PyChromecast'} 16-12-05 14:12:34 pychromecast.socket_client: Sending: Message urn:x-cast:com.google.cast.receiver from sender-0 to receiver-0: {'type': 'GET_STATUS', 'requestId': 1} 16-12-05 14:12:34 pychromecast.socket_client: Connected! 16-12-05 14:12:34 homeassistant.core: Bus:Handling <Event state_changed[L]: old_state=None, new_state=<state media_player.master_bedroom_home=off; friendly_name=Master Bedroom Home, supported_media_commands=5053 @ 2016-12-05T09:12:34.309245-05:00>, entity_id=media_player.master_bedroom_home> 16-12-05 14:12:34 homeassistant.components.websocket_api: WS 1770536464: Sending {'id': 2, 'type': 'event', 'event': {'origin': 'LOCAL', 'data': {'old_state': None, 'new_state': <state media_player.master_bedroom_home=off; friendly_name=Master Bedroom Home, supported_media_commands=5053 @ 2016-12-05T09:12:34.309245-05:00>, 'entity_id': 'media_player.master_bedroom_home'}, 'event_type': 'state_changed', 'time_fired': datetime.datetime(2016, 12, 5, 14, 12, 34, 309449, tzinfo=)}} 16-12-05 14:12:34 pychromecast.socket_client: Received: Message urn:x-cast:com.google.cast.receiver from receiver-0 to sender-0: {'type': 'RECEIVER_STATUS', 'status': {'volume': {'stepInterval': 0.05000000074505806, 'controlType': 'attenuation', 'muted': False, 'level': 0.5199999809265137}}, 'requestId': 1} 16-12-05 14:12:34 pychromecast.controllers: Received status: CastStatus(is_active_input=False, is_stand_by=True, volume_level=0.5199999809265137, volume_muted=False, app_id=None, display_name=None, namespaces=[], session_id=None, transport_id=None, status_text='') 16-12-05 14:13:00 phue: GET /api/UuJqs2FXkpUeelWxz8zlJswxfgZ0AwoMcdeXq-IQ None 16-12-05 14:13:30 phue: GET /api/UuJqs2FXkpUeelWxz8zlJswxfgZ0AwoMcdeXq-IQ None 16-12-05 14:13:30 homeassistant.core: Bus:Handling <Event state_changed[L]: old_state=<state sun.sun=above_horizon; friendly_name=Sun, next_setting=2016-12-05T22:28:47+00:00, next_rising=2016-12-06T12:04:33+00:00, azimuth=134.03, elevation=22.63 @ 2016-12-05T09:11:56.476740-05:00>, new_state=<state sun.sun=above_horizon; friendly_name=Sun, next_setting=2016-12-05T22:28:47+00:00, next_rising=2016-12-06T12:04:33+00:00, azimuth=134.21, elevation=22.78 @ 2016-12-05T09:11:56.476740-05:00>, entity_id=sun.sun> 16-12-05 14:13:30 homeassistant.components.websocket_api: WS 1770536464: Sending {'id': 2, 'type': 'event', 'event': {'origin': 'LOCAL', 'data': {'old_state': <state sun.sun=above_horizon; friendly_name=Sun, next_setting=2016-12-05T22:28:47+00:00, next_rising=2016-12-06T12:04:33+00:00, azimuth=134.03, elevation=22.63 @ 2016-12-05T09:11:56.476740-05:00>, 'new_state': <state sun.sun=above_horizon; friendly_name=Sun, next_setting=2016-12-05T22:28:47+00:00, next_rising=2016-12-06T12:04:33+00:00, azimuth=134.21, elevation=22.78 @ 2016-12-05T09:11:56.476740-05:00>, 'entity_id': 'sun.sun'}, 'event_type': 'state_changed', 'time_fired': datetime.datetime(2016, 12, 5, 14, 13, 30, 800854, tzinfo=)}} 16-12-05 14:14:00 phue: GET /api/UuJqs2FXkpUeelWxz8zlJswxfgZ0AwoMcdeXq-IQ None 16-12-05 14:14:30 phue: GET /api/UuJqs2FXkpUeelWxz8zlJswxfgZ0AwoMcdeXq-IQ None 16-12-05 14:14:30 homeassistant.core: Bus:Handling <Event state_changed[L]: old_state=<state sun.sun=above_horizon; friendly_name=Sun, next_setting=2016-12-05T22:28:47+00:00, next_rising=2016-12-06T12:04:33+00:00, azimuth=134.21, elevation=22.78 @ 2016-12-05T09:11:56.476740-05:00>, new_state=<state sun.sun=above_horizon; friendly_name=Sun, next_setting=2016-12-05T22:28:47+00:00, next_rising=2016-12-06T12:04:33+00:00, azimuth=134.39, elevation=22.94 @ 2016-12-05T09:11:56.476740-05:00>, entity_id=sun.sun> 16-12-05 14:14:30 homeassistant.components.websocket_api: WS 1770536464: Sending {'id': 2, 'type': 'event', 'event': {'origin': 'LOCAL', 'data': {'old_state': <state sun.sun=above_horizon; friendly_name=Sun, next_setting=2016-12-05T22:28:47+00:00, next_rising=2016-12-06T12:04:33+00:00, azimuth=134.21, elevation=22.78 @ 2016-12-05T09:11:56.476740-05:00>, 'new_state': <state sun.sun=above_horizon; friendly_name=Sun, next_setting=2016-12-05T22:28:47+00:00, next_rising=2016-12-06T12:04:33+00:00, azimuth=134.39, elevation=22.94 @ 2016-12-05T09:11:56.476740-05:00>, 'entity_id': 'sun.sun'}, 'event_type': 'state_changed', 'time_fired': datetime.datetime(2016, 12, 5, 14, 14, 30, 687611, tzinfo=)}} 16-12-05 14:14:30 phue: GET /api/UuJqs2FXkpUeelWxz8zlJswxfgZ0AwoMcdeXq-IQ None 16-12-05 14:15:00 phue: GET /api/UuJqs2FXkpUeelWxz8zlJswxfgZ0AwoMcdeXq-IQ None

`

arsaboo commented 7 years ago

Can confirm....seeing similar messages in my logs as well.