Home Assistant v 0.11.1 with switch support

martinhjelmare

@drock1985

Please show me the home assistant log. I can't really say anything useful without it.

drock1985

here is the output from /home/pi/.homeassistant/home-assistant.log:

6-01-24 15:08:16 netdisco.ssdp: Found malformed XML at http://192.168.86.142:9080: status=ok

This is my configuration.yaml

homeassistant:
  # Name of the location where Home Assistant is running
  name: Home
  # Location required to calculate the time the sun rises and sets
  latitude: 
  longitude: 
  # C for Celcius, F for Fahrenheit
  temperature_unit: C
  # Pick yours from here: http://en.wikipedia.org/wiki/List_of_tz_database_time_zones
  time_zone: America/Halifax

# Enables the frontend
frontend:

# Checks for available updates
updater:

# View all events in a logbook
logbook:
# Track the sun
sun:

# Enables support for tracking state changes over time.
history:

# Show links to resources in log and frontend
#introduction:

# Allows you to issue voice commands from the frontend
conversation:

# Discover some devices automatically
discovery:

# Webcams
camera 1:
  platform: mjpeg
  mjpeg_url: http://192.168.86.117:10012/videofeed
  name: LivingRoom Camera
  username: root
  password: groot

# Squeezebox Server
media_player 1:
  platform: squeezebox
  host: 192.168.86.113
  port: 9090

# MySensors Serial Gateway

mysensors:
  gateways:
    - port: '/dev/ttyUSB0'
      persistence_file: '/home/pi/.homeassistant/mysensors.json'
#  debug: true
  persistence: true
  version: '1.5'

Would it be because my nodes are set for manual ID and not auto?

martinhjelmare

@drock1985

The log file only outputs errors as default. Try starting home assistant from the command line with hass and then copy the output. It should be a lot more than one line.

You should shut down home assistant first if you have it running as a service.

Activate debug first for mysensors though.

drock1985

@martinhjelmare

Here is the .log file

16-01-24 15:25:28 mysensors.mysensors: Error decoding message from gateway, probably received bad byte.

And from the SSH console after running hass as instructed

INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state media_player.master_bedroom=off; supported_media_commands=1021, friendly_name=Master Bedroom @ 15:25:41 24-01-2016>, entity_id=media_player.master_bedroom>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state media_player.man_cave=off; supported_media_commands=1021, friendly_name=Man Cave @ 15:25:41 24-01-2016>, entity_id=media_player.man_cave>
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:pywemo.subscribe:Subscribing to events from <WeMo Switch "CatFan">
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:pywemo.subscribe:Subscribing to events from <WeMo Switch "LivingRoomLamp">
INFO:pywemo.subscribe:Resubscribe for <WeMo Switch "CatFan">
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state media_player.living_room=off; supported_media_commands=1021, friendly_name=Living Room @ 15:25:41 24-01-2016>, entity_id=media_player.living_room>
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state switch.catfan=off; friendly_name=CatFan @ 15:25:41 24-01-2016>, entity_id=switch.catfan>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state group.all_switches=unknown; auto=True, entity_id=[], friendly_name=all switches @ 15:25:41 24-01-2016>, entity_id=group.all_switches>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state group.all_switches=off; auto=True, entity_id=('switch.catfan',), friendly_name=all switches @ 15:25:41 24-01-2016>, entity_id=group.all_switches>
INFO:pywemo.subscribe:Resubscribe for <WeMo Switch "LivingRoomLamp">
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:pywemo.subscribe:Received event from <WeMo Switch "CatFan">(192.168.86.108)
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:homeassistant.components.switch.wemo:Subscription update for  <WeMo Switch "CatFan">
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state switch.livingroomlamp=off; friendly_name=LivingRoomLamp @ 15:25:42 24-01-2016>, entity_id=switch.livingroomlamp>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state group.all_switches=off; auto=True, entity_id=('switch.catfan', 'switch.livingroomlamp'), friendly_name=all switches @ 15:25:42 24-01-2016>, entity_id=group.all_switches>
INFO:pywemo.subscribe:Received event from <WeMo Switch "LivingRoomLamp">(192.168.86.163)
INFO:homeassistant.components.switch.wemo:Subscription update for  <WeMo Switch "LivingRoomLamp">
INFO:pywemo.subscribe:Subscribing to events from <WeMo Insight "Christmas Tree Lights">
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:pywemo.subscribe:Resubscribe for <WeMo Insight "Christmas Tree Lights">
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state switch.christmas_tree_lights=off; friendly_name=Christmas Tree Lights @ 15:25:42 24-01-2016>, entity_id=switch.christmas_tree_lights>
INFO:pywemo.subscribe:Received event from <WeMo Insight "Christmas Tree Lights">(192.168.86.111)
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state group.all_switches=off; auto=True, entity_id=('switch.christmas_tree_lights', 'switch.catfan', 'switch.livingroomlamp'), friendly_name=all switches @ 15:25:42 24-01-2016>, entity_id=group.all_switches>
INFO:homeassistant.components.switch.wemo:Subscription update for  <WeMo Insight "Christmas Tree Lights">
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:pywemo.subscribe:Received event from <WeMo Insight "Christmas Tree Lights">(192.168.86.111)
INFO:homeassistant.components.switch.wemo:Subscription update for  <WeMo Insight "Christmas Tree Lights">
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.117
INFO:homeassistant.components.http:"GET /states HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /static/frontend-1003c31441ec44b3db84b49980f736a7.html HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /static/favicon-192x192.png HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /static/favicon.ico HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /api/bootstrap HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /api/stream?api_password=no_password_set&restrict=state_changed,component_loaded,service_registered HTTP/1.1" 200 -
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.117
INFO:homeassistant.components.media_player:Updating media_player entities
INFO:homeassistant.components.http:"GET /api/error_log HTTP/1.1" 200 -
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 1-1-0 s=2,c=1,t=16,pt=0,l=1,sg=0:1
INFO:homeassistant.components.media_player:Updating media_player entities
INFO:homeassistant.components.light:Updating light entities
INFO:homeassistant.components.camera:Updating camera entities
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state camera.livingroom_camera=idle; entity_picture=/api/camera_proxy/camera.livingroom_camera?time=1453663560.5128732, friendly_name=LivingRoom Camera @ 15:25:24 24-01-2016>, old_state=<state camera.livingroom_camera=idle; entity_picture=/api/camera_proxy/camera.livingroom_camera?time=1453663530.5116684, friendly_name=LivingRoom Camera @ 15:25:24 24-01-2016>, entity_id=camera.livingroom_camera>
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 3-3-0 s=255,c=0,t=17,pt=0,l=5,sg=0:1.5.1
INFO:homeassistant.components.mysensors:update sensor_update: node 3
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 3-3-0 s=255,c=3,t=6,pt=1,l=1,sg=0:0
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:send: 0-0-3-3 s=255,c=3,t=6,pt=0,l=1,sg=0,st=ok:M
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 1-1-0 s=2,c=1,t=16,pt=0,l=1,sg=0:1
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 3-3-0 s=255,c=3,t=11,pt=0,l=25,sg=0:2 Door bell/chime
INFO:homeassistant.components.mysensors:update sensor_update: node 3
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 3-3-0 s=255,c=3,t=12,pt=0,l=3,sg=0:1.0
INFO:homeassistant.components.mysensors:update sensor_update: node 3
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 3-3-0 s=1,c=0,t=3,pt=0,l=0,sg=0:
INFO:homeassistant.components.mysensors:update sensor_update: node 3
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 3-3-0 s=0,c=0,t=1,pt=0,l=0,sg=0:
INFO:homeassistant.components.mysensors:update sensor_update: node 3
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 3-3-0 s=3,c=0,t=3,pt=0,l=0,sg=0:
INFO:homeassistant.components.mysensors:update sensor_update: node 3
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 3-3-0 s=2,c=0,t=1,pt=0,l=0,sg=0:
INFO:homeassistant.components.mysensors:update sensor_update: node 3
INFO:homeassistant.components.media_player:Updating media_player entities
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.117
INFO:homeassistant.components.http:"GET /states HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /static/frontend-1003c31441ec44b3db84b49980f736a7.html HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /static/favicon-192x192.png HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /static/favicon.ico HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /api/bootstrap HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /api/stream?api_password=no_password_set&restrict=state_changed,component_loaded,service_registered HTTP/1.1" 200 -
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.117
INFO:homeassistant.components.api:Found broken event stream to 192.168.86.162, cleaning up
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 1-1-0 s=2,c=1,t=16,pt=0,l=1,sg=0:1
INFO:homeassistant.components.media_player:Updating media_player entities
INFO:homeassistant.components.media_player:Updating media_player entities
INFO:homeassistant.components.light:Updating light entities
INFO:homeassistant.components.camera:Updating camera entities
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state camera.livingroom_camera=idle; entity_picture=/api/camera_proxy/camera.livingroom_camera?time=1453663590.5271018, friendly_name=LivingRoom Camera @ 15:25:24 24-01-2016>, old_state=<state camera.livingroom_camera=idle; entity_picture=/api/camera_proxy/camera.livingroom_camera?time=1453663560.5128732, friendly_name=LivingRoom Camera @ 15:25:24 24-01-2016>, entity_id=camera.livingroom_camera>
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.117
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state sun.sun=above_horizon; next_rising=11:43:05 25-01-2016, elevation=14.76, next_setting=21:15:13 24-01-2016, friendly_name=Sun @ 15:25:24 24-01-2016>, old_state=<state sun.sun=above_horizon; next_rising=11:43:05 25-01-2016, elevation=14.88, next_setting=21:15:13 24-01-2016, friendly_name=Sun @ 15:25:24 24-01-2016>, entity_id=sun.sun>
INFO:homeassistant.components.api:Found broken event stream to 192.168.86.162, cleaning up
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 1-1-0 s=0,c=1,t=1,pt=7,l=5,sg=0:46.5
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 1-1-0 s=2,c=1,t=16,pt=0,l=1,sg=0:1
INFO:homeassistant.components.media_player:Updating media_player entities
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 1-1-0 s=2,c=1,t=16,pt=0,l=1,sg=0:1
INFO:homeassistant.components.media_player:Updating media_player entities
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 1-1-0 s=0,c=1,t=1,pt=7,l=5,sg=0:46.6
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 1-1-0 s=2,c=1,t=16,pt=0,l=1,sg=0:1
INFO:homeassistant.components.media_player:Updating media_player entities
INFO:homeassistant.components.light:Updating light entities
INFO:homeassistant.components.camera:Updating camera entities
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state camera.livingroom_camera=idle; entity_picture=/api/camera_proxy/camera.livingroom_camera?time=1453663620.51471, friendly_name=LivingRoom Camera @ 15:25:24 24-01-2016>, old_state=<state camera.livingroom_camera=idle; entity_picture=/api/camera_proxy/camera.livingroom_camera?time=1453663590.5271018, friendly_name=LivingRoom Camera @ 15:25:24 24-01-2016>, entity_id=camera.livingroom_camera>
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.117
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 1-1-0 s=2,c=1,t=16,pt=0,l=1,sg=0:1
INFO:homeassistant.components.media_player:Updating media_player entities
INFO:homeassistant.components.media_player:Updating media_player entities
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 1-1-0 s=2,c=1,t=16,pt=0,l=1,sg=0:1
INFO:homeassistant.components.media_player:Updating media_player entities
INFO:homeassistant.components.light:Updating light entities
INFO:homeassistant.components.camera:Updating camera entities
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state camera.livingroom_camera=idle; entity_picture=/api/camera_proxy/camera.livingroom_camera?time=1453663650.5204854, friendly_name=LivingRoom Camera @ 15:25:24 24-01-2016>, old_state=<state camera.livingroom_camera=idle; entity_picture=/api/camera_proxy/camera.livingroom_camera?time=1453663620.51471, friendly_name=LivingRoom Camera @ 15:25:24 24-01-2016>, entity_id=camera.livingroom_camera>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state sun.sun=above_horizon; next_rising=11:43:05 25-01-2016, elevation=14.63, next_setting=21:15:13 24-01-2016, friendly_name=Sun @ 15:25:24 24-01-2016>, old_state=<state sun.sun=above_horizon; next_rising=11:43:05 25-01-2016, elevation=14.76, next_setting=21:15:13 24-01-2016, friendly_name=Sun @ 15:25:24 24-01-2016>, entity_id=sun.sun>
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 1-1-0 s=0,c=1,t=1,pt=7,l=5,sg=0:46.5
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 1-1-0 s=2,c=1,t=16,pt=0,l=1,sg=0:1

martinhjelmare

@drock1985

Is it node 3 that is missing for you? It seems you're presenting it fine but never send the needed value for the four sensors, 0-3, on the node.

Then you also have node 1 with two sensors 0 and 2 that seems to update, but you haven't posted the beginning of the log where those are presented or loaded from persistence. I'm also missing the gateway started confirmation in the log.

drock1985

Hi @martinhjelmare

the file /home/pi/.homeassistant/home-assistant.log has no errors in it, and it's all of my nodes that are missing (1 through 4 and 6), Node 5 isn't online right now.

Is that what you are looking for at the top?


INFO:homeassistant.components.recorder:Closing database
pi@home-assistant:~/.homeassistant $ clear
pi@home-assistant:~/.homeassistant $ hass
Config directory: /home/pi/.homeassistant
WARNING:homeassistant.bootstrap:Colorlog package not found, console coloring disabled
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=homeassistant, service=turn_off>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=homeassistant, service=turn_on>
INFO:homeassistant.bootstrap:Home Assistant core initialized
INFO:homeassistant.loader:Loaded mysensors from homeassistant.components.mysensors
INFO:homeassistant.loader:Loaded media_player from homeassistant.components.media_player
INFO:homeassistant.loader:Loaded sun from homeassistant.components.sun
INFO:homeassistant.loader:Loaded updater from homeassistant.components.updater
INFO:homeassistant.loader:Loaded discovery from homeassistant.components.discovery
INFO:homeassistant.loader:Loaded conversation from homeassistant.components.conversation
INFO:homeassistant.loader:Loaded frontend from homeassistant.components.frontend
INFO:homeassistant.loader:Loaded api from homeassistant.components.api
INFO:homeassistant.loader:Loaded http from homeassistant.components.http
INFO:homeassistant.loader:Loaded logbook from homeassistant.components.logbook
INFO:homeassistant.loader:Loaded recorder from homeassistant.components.recorder
INFO:homeassistant.loader:Loaded history from homeassistant.components.history
INFO:homeassistant.loader:Loaded camera from homeassistant.components.camera
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=recorder>
INFO:homeassistant.loader:Loaded sensor from homeassistant.components.sensor
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=sensor>
INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: service=mysensors.sensors, discovered=>
INFO:homeassistant.loader:Loaded switch from homeassistant.components.switch
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=switch, service=turn_off>
INFO:homeassistant.loader:Loaded sensor.mysensors from homeassistant.components.sensor.mysensors
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=switch, service=turn_on>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=switch>
INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: service=mysensors.switches, discovered=>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=mysensors>
INFO:homeassistant.loader:Loaded switch.mysensors from homeassistant.components.switch.mysensors
INFO:homeassistant.loader:Loaded media_player.squeezebox from homeassistant.components.media_player.squeezebox
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state media_player.airspeaker=off; friendly_name=AirSpeaker, supported_media_commands=447 @ 17:05:03 24-01-2016>, entity_id=media_player.airspeaker>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=media_play>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=volume_down>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=media_play_pause>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=media_pause>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=volume_up>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=media_previous_track>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=turn_off>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=play_media>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=media_next_track>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=turn_on>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=volume_set>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=volume_mute>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=media_seek>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=start_fireplace>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=start_epic_sax>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=play_youtube_video>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=play_media>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=media_player>
INFO:urllib3.connectionpool:Starting new HTTP connection (1): maps.googleapis.com
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state sun.sun=above_horizon; next_setting=21:15:13 24-01-2016, next_rising=11:43:05 25-01-2016, friendly_name=Sun, elevation=1.17 @ 17:05:03 24-01-2016>, entity_id=sun.sun>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=sun>
INFO:urllib3.connectionpool:Starting new HTTPS connection (1): pypi.python.org
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=updater>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=discovery>
/home/pi/.homeassistant/lib/fuzzywuzzy/fuzz.py:33: UserWarning: Using slow pure-python SequenceMatcher. Install python-Levenshtein to remove this warning
  warnings.warn('Using slow pure-python SequenceMatcher. Install python-Levenshtein to remove this warning')
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=conversation, service=process>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=conversation>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=http>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=api>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=frontend>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=logbook>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=history>
INFO:homeassistant.loader:Loaded camera.mjpeg from homeassistant.components.camera.mjpeg
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state camera.livingroom_camera=idle; friendly_name=LivingRoom Camera, entity_picture=/api/camera_proxy/camera.livingroom_camera?time=1453669504.2007444 @ 17:05:04 24-01-2016>, entity_id=camera.livingroom_camera>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=camera>
INFO:homeassistant.core:Starting Home Assistant (17 threads)
INFO:homeassistant.core:Bus:Handling <Event homeassistant_start[L]>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=homeassistant, service=stop>
INFO:mysensors.mysensors:Trying to connect to /dev/ttyUSB0
INFO:homeassistant.core:Timer:starting
INFO:homeassistant.components.http:Starting web interface at http://0.0.0.0:8123
INFO:homeassistant.components.mysensors:update persistence: node 2
INFO:homeassistant.components.mysensors:No sketch_name: node 2
INFO:homeassistant.components.mysensors:No sketch_name: node 2
INFO:homeassistant.components.mysensors:update persistence: node 3
INFO:netdisco.service:Scanning
INFO:homeassistant.components.http:"GET /api/stream?api_password=no_password_set&restrict=state_changed,component_loaded,service_registered HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /api/bootstrap HTTP/1.1" 200 -
INFO:mysensors.mysensors:/dev/ttyUSB0 is open...
INFO:mysensors.mysensors:Connected to /dev/ttyUSB0
WARNING:mysensors.mysensors:Error decoding message from gateway, probably received bad byte.
INFO:homeassistant.components.media_player:Updating media_player entities
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 1-1-0 s=2,c=1,t=16,pt=0,l=1,sg=0:1
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.4
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.1
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.113
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.113
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.101
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.142
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.199
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.102
INFO:homeassistant.components.discovery:Found new service: DLNA http://192.168.86.4:32469/DeviceDescription.xml
INFO:homeassistant.components.discovery:Found new service: DLNA http://192.168.86.113:9000/plugins/UPnP/MediaServer.xml
INFO:homeassistant.components.discovery:Found new service: google_cast ('192.168.86.101', 8009)
INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: service=google_cast, discovered=('192.168.86.101', 8009)>
INFO:homeassistant.components.discovery:Found new service: google_cast ('192.168.86.102', 8009)
INFO:homeassistant.loader:Loaded media_player.cast from homeassistant.components.media_player.cast
INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: service=google_cast, discovered=('192.168.86.102', 8009)>
INFO:homeassistant.components.discovery:Found new service: belkin_wemo ('Christmas Tree Lights', 'Insight', 'http://192.168.86.111:49153/setup.xml', '94103E395F2C')
INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: service=belkin_wemo, discovered=('Christmas Tree Lights', 'Insight', 'http://192.168.86.111:49153/setup.xml', '94103E395F2C')>
INFO:homeassistant.components.discovery:Found new service: belkin_wemo ('CatFan', 'Socket', 'http://192.168.86.108:49153/setup.xml', '94103E30CDBC')
INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: service=belkin_wemo, discovered=('CatFan', 'Socket', 'http://192.168.86.108:49153/setup.xml', '94103E30CDBC')>
INFO:homeassistant.components.discovery:Found new service: belkin_wemo ('LivingRoomLamp', 'Socket', 'http://192.168.86.163:49153/setup.xml', '94103E30C688')
INFO:homeassistant.loader:Loaded switch.wemo from homeassistant.components.switch.wemo
INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: service=belkin_wemo, discovered=('LivingRoomLamp', 'Socket', 'http://192.168.86.163:49153/setup.xml', '94103E30C688')>
INFO:homeassistant.loader:Loaded switch.wemo from homeassistant.components.switch.wemo
ERROR:homeassistant.components.switch:Error while setting up platform wemo
Traceback (most recent call last):
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/helpers/entity_component.py", line 145, in _setup_platform
    self.hass, platform_config, self.add_entities, discovery_info)
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/components/switch/wemo.py", line 25, in setup_platform
    import pywemo.discovery as discovery
AttributeError: 'module' object has no attribute 'discovery'
ERROR:homeassistant.components.switch:Error while setting up platform wemo
Traceback (most recent call last):
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/helpers/entity_component.py", line 145, in _setup_platform
    self.hass, platform_config, self.add_entities, discovery_info)
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/components/switch/wemo.py", line 25, in setup_platform
    import pywemo.discovery as discovery
AttributeError: 'module' object has no attribute 'discovery'
ERROR:homeassistant.components.switch:Error while setting up platform wemo
Traceback (most recent call last):
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/helpers/entity_component.py", line 145, in _setup_platform
    self.hass, platform_config, self.add_entities, discovery_info)
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/components/switch/wemo.py", line 25, in setup_platform
    import pywemo.discovery as discovery
AttributeError: 'module' object has no attribute 'discovery'
INFO:pychromecast:Querying device status
INFO:pychromecast:Querying device status
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.102
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.101
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state media_player.man_cave=off; friendly_name=Man Cave, supported_media_commands=1021 @ 17:05:19 24-01-2016>, entity_id=media_player.man_cave>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state media_player.master_bedroom=off; friendly_name=Master Bedroom, supported_media_commands=1021 @ 17:05:19 24-01-2016>, entity_id=media_player.master_bedroom>
INFO:homeassistant.components.discovery:Found new service: plex_mediaserver ('Rocknet Server', 'https://192.168.86.4:32400')
INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: service=plex_mediaserver, discovered=('Rocknet Server', 'https://192.168.86.4:32400')>
INFO:homeassistant.components.discovery:Found new service: philips_hue ('Philips hue (192.168.86.199)', 'http://192.168.86.199:80/')
INFO:homeassistant.loader:Loaded media_player.plex from homeassistant.components.media_player.plex
INFO:homeassistant.loader:Loaded light from homeassistant.components.light
INFO:homeassistant.components.media_player:Updating media_player entities
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=light, service=turn_on>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=light, service=turn_off>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=light>
INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: service=philips_hue, discovered=('Philips hue (192.168.86.199)', 'http://192.168.86.199:80/')>
INFO:homeassistant.loader:Loaded light.hue from homeassistant.components.light.hue
INFO:phue:Attempting to connect to the bridge...
INFO:phue:Using ip: 192.168.86.199
INFO:phue:Using username from config: 30f609e6210320e41f0dfc32220a525f
Exception in thread Thread-25:
Traceback (most recent call last):
  File "/usr/lib/python3.4/threading.py", line 920, in _bootstrap_inner
    self.run()
  File "/home/pi/.homeassistant/lib/zeroconf.py", line 1063, in run
    handler(self.zc)
  File "/home/pi/.homeassistant/lib/zeroconf.py", line 1014, in <lambda>
    state_change=state_change,
  File "/home/pi/.homeassistant/lib/zeroconf.py", line 931, in fire
    h(**kwargs)
  File "/home/pi/.homeassistant/lib/zeroconf.py", line 989, in on_change
    listener.add_service(*args)
  File "/home/pi/.homeassistant/lib/netdisco/discoverables/__init__.py", line 84, in add_service
    service = zconf.get_service_info(typ, name)
  File "/home/pi/.homeassistant/lib/zeroconf.py", line 1404, in get_service_info
    if info.request(self, timeout):
  File "/home/pi/.homeassistant/lib/zeroconf.py", line 1221, in request
    zc.send(out)
  File "/home/pi/.homeassistant/lib/zeroconf.py", line 1663, in send
    bytes_sent = s.sendto(packet, 0, (addr, port))
OSError: [Errno 9] Bad file descriptor

INFO:plexapi:GET http://192.168.86.4:32400/
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.4
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state light.master_bedroom_1=off; friendly_name=Master bedroom 1 @ 17:05:20 24-01-2016>, entity_id=light.master_bedroom_1>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state light.master_bedroom_2=off; friendly_name=Master bedroom 2 @ 17:05:20 24-01-2016>, entity_id=light.master_bedroom_2>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state light.aprils_lamp=off; friendly_name=April's lamp @ 17:05:20 24-01-2016>, entity_id=light.aprils_lamp>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state light.living_room_light_1=off; friendly_name=Living room light 1 @ 17:05:20 24-01-2016>, entity_id=light.living_room_light_1>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state light.master_bedroom_light_strip=off; friendly_name=Master bedroom light strip @ 17:05:20 24-01-2016>, entity_id=light.master_bedroom_light_strip>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state light.living_room_light_2=off; friendly_name=Living room light 2 @ 17:05:20 24-01-2016>, entity_id=light.living_room_light_2>
INFO:homeassistant.components.media_player.plex:Connected to: htts://192.168.86.4:32400
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state light.master_bedroom_3=off; friendly_name=Master bedroom 3 @ 17:05:20 24-01-2016>, entity_id=light.master_bedroom_3>
INFO:plexapi:GET http://192.168.86.4:32400/clients
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state group.all_lights=unknown; entity_id=[], friendly_name=all lights, auto=True @ 17:05:20 24-01-2016>, entity_id=group.all_lights>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state group.all_lights=off; entity_id=('light.living_room_light_1', 'light.living_room_light_2', 'light.master_bedroom_3', 'light.master_bedroom_2', 'light.master_bedroom_1', 'light.master_bedroom_light_strip', 'light.aprils_lamp'), friendly_name=all lights, auto=True @ 17:05:20 24-01-2016>, entity_id=group.all_lights>
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.4
INFO:plexapi:GET http://192.168.86.4:32400/status/sessions
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.4
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 1-1-0 s=2,c=1,t=16,pt=0,l=1,sg=0:1
INFO:homeassistant.components.camera:Updating camera entities
INFO:homeassistant.components.light:Updating light entities
INFO:homeassistant.components.media_player:Updating media_player entities
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state camera.livingroom_camera=idle; friendly_name=LivingRoom Camera, entity_picture=/api/camera_proxy/camera.livingroom_camera?time=1453669530.5242217 @ 17:05:04 24-01-2016>, entity_id=camera.livingroom_camera, old_state=<state camera.livingroom_camera=idle; friendly_name=LivingRoom Camera, entity_picture=/api/camera_proxy/camera.livingroom_camera?time=1453669504.2007444 @ 17:05:04 24-01-2016>>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state sun.sun=above_horizon; next_setting=21:15:13 24-01-2016, next_rising=11:43:05 25-01-2016, friendly_name=Sun, elevation=1.1 @ 17:05:03 24-01-2016>, entity_id=sun.sun, old_state=<state sun.sun=above_horizon; next_setting=21:15:13 24-01-2016, next_rising=11:43:05 25-01-2016, friendly_name=Sun, elevation=1.17 @ 17:05:03 24-01-2016>>
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 3-3-0 s=255,c=0,t=17,pt=0,l=5,sg=0:1.5.1
INFO:homeassistant.components.mysensors:update sensor_update: node 3
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 3-3-0 s=255,c=3,t=6,pt=1,l=1,sg=0:0
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:send: 0-0-3-3 s=255,c=3,t=6,pt=0,l=1,sg=0,st=ok:M
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 3-3-0 s=255,c=3,t=11,pt=0,l=25,sg=0:2 Door bell/chime
INFO:homeassistant.components.mysensors:update sensor_update: node 3
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 3-3-0 s=255,c=3,t=12,pt=0,l=3,sg=0:1.0
INFO:homeassistant.components.mysensors:update sensor_update: node 3
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 3-3-0 s=1,c=0,t=3,pt=0,l=0,sg=0:
INFO:homeassistant.components.mysensors:update sensor_update: node 3
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 3-3-0 s=0,c=0,t=1,pt=0,l=0,sg=0:
INFO:homeassistant.components.mysensors:update sensor_update: node 3
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 3-3-0 s=3,c=0,t=3,pt=0,l=0,sg=0:
INFO:homeassistant.components.mysensors:update sensor_update: node 3
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 3-3-0 s=2,c=0,t=1,pt=0,l=0,sg=0:
INFO:homeassistant.components.mysensors:update sensor_update: node 3
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.117
INFO:homeassistant.components.media_player:Updating media_player entities
INFO:homeassistant.components.http:"GET /states HTTP/1.1" 200 -
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 1-1-0 s=2,c=1,t=16,pt=0,l=1,sg=0:1
INFO:homeassistant.components.http:"GET /static/frontend-1003c31441ec44b3db84b49980f736a7.html HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /static/favicon-192x192.png HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /static/favicon.ico HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /api/bootstrap HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /api/stream?api_password=no_password_set&restrict=state_changed,component_loaded,service_registered HTTP/1.1" 200 -
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.117
INFO:homeassistant.core:Bus:Handling <Event call_service[L]: domain=homeassistant, entity_id=light.living_room_light_1, service_call_id=1981876976-1, service=turn_on>
INFO:homeassistant.loader:Loaded group from homeassistant.components.group
INFO:homeassistant.core:Bus:Handling <Event call_service[L]: domain=light, entity_id=['light.living_room_light_1'], service_call_id=1981876976-2, service=turn_on>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state light.living_room_light_1=on; brightness=254, friendly_name=Living room light 1 @ 17:05:47 24-01-2016>, entity_id=light.living_room_light_1, old_state=<state light.living_room_light_1=off; friendly_name=Living room light 1 @ 17:05:20 24-01-2016>>
INFO:homeassistant.core:Bus:Handling <Event service_executed[L]: service_call_id=1981876976-2>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state group.all_lights=on; entity_id=('light.living_room_light_1', 'light.living_room_light_2', 'light.master_bedroom_3', 'light.master_bedroom_2', 'light.master_bedroom_1', 'light.master_bedroom_light_strip', 'light.aprils_lamp'), friendly_name=all lights, auto=True @ 17:05:47 24-01-2016>, entity_id=group.all_lights, old_state=<state group.all_lights=off; entity_id=('light.living_room_light_1', 'light.living_room_light_2', 'light.master_bedroom_3', 'light.master_bedroom_2', 'light.master_bedroom_1', 'light.master_bedroom_light_strip', 'light.aprils_lamp'), friendly_name=all lights, auto=True @ 17:05:20 24-01-2016>>
INFO:homeassistant.core:Bus:Handling <Event service_executed[L]: service_call_id=1981876976-1>
INFO:homeassistant.components.api:Found broken event stream to 192.168.86.162, cleaning up
INFO:homeassistant.components.http:"POST /api/services/homeassistant/turn_on HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /api/history/period?filter_entity_id=light.living_room_light_2 HTTP/1.1" 200 -
INFO:homeassistant.core:Bus:Handling <Event call_service[L]: domain=homeassistant, entity_id=light.living_room_light_2, service_call_id=1981876976-3, service=turn_on>
INFO:homeassistant.core:Bus:Handling <Event call_service[L]: domain=light, entity_id=['light.living_room_light_2'], service_call_id=1981876976-4, service=turn_on>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state light.living_room_light_2=on; brightness=254, friendly_name=Living room light 2 @ 17:05:49 24-01-2016>, entity_id=light.living_room_light_2, old_state=<state light.living_room_light_2=off; friendly_name=Living room light 2 @ 17:05:20 24-01-2016>>
INFO:homeassistant.core:Bus:Handling <Event service_executed[L]: service_call_id=1981876976-4>
INFO:homeassistant.core:Bus:Handling <Event service_executed[L]: service_call_id=1981876976-3>
INFO:homeassistant.components.http:"POST /api/services/homeassistant/turn_on HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /api/history/period?filter_entity_id=light.living_room_light_1 HTTP/1.1" 200 -
INFO:homeassistant.components.media_player:Updating media_player entities
INFO:homeassistant.components.http:"GET /states HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /static/frontend-1003c31441ec44b3db84b49980f736a7.html HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /static/favicon-192x192.png HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /static/favicon.ico HTTP/1.1" 200 -
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 1-1-0 s=2,c=1,t=16,pt=0,l=1,sg=0:1
INFO:homeassistant.components.http:"GET /api/bootstrap HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /api/stream?api_password=no_password_set&restrict=state_changed,component_loaded,service_registered HTTP/1.1" 200 -
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.117
INFO:homeassistant.components.http:"GET /api/error_log HTTP/1.1" 200 -
INFO:homeassistant.components.camera:Updating camera entities
INFO:homeassistant.components.light:Updating light entities
INFO:homeassistant.components.media_player:Updating media_player entities
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state camera.livingroom_camera=idle; friendly_name=LivingRoom Camera, entity_picture=/api/camera_proxy/camera.livingroom_camera?time=1453669560.5092123 @ 17:05:04 24-01-2016>, entity_id=camera.livingroom_camera, old_state=<state camera.livingroom_camera=idle; friendly_name=LivingRoom Camera, entity_picture=/api/camera_proxy/camera.livingroom_camera?time=1453669530.5242217 @ 17:05:04 24-01-2016>>
INFO:homeassistant.components.http:"GET /devInfo HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /static/frontend-1003c31441ec44b3db84b49980f736a7.html HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /static/favicon-192x192.png HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /static/favicon.ico HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /api/bootstrap HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /api/stream?api_password=no_password_set&restrict=state_changed,component_loaded,service_registered HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /api/error_log HTTP/1.1" 200 -
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.117
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 1-1-0 s=2,c=1,t=16,pt=0,l=1,sg=0:1
INFO:homeassistant.components.media_player:Updating media_player entities
^CINFO:homeassistant.core:Stopping
INFO:homeassistant.core:Bus:Handling <Event homeassistant_stop[L]>
INFO:mysensors.mysensors:Disconnecting from /dev/ttyUSB0
INFO:mysensors.mysensors:Stopping thread
INFO:homeassistant.components.recorder:Closing database
pi@home-assistant:~/.homeassistant $

drock1985

I'm still trying to get this to work, with mixed results. Noticed on startup sometimes that HA was starting before my Serial port was initialized. Added a .timer file to delay the start of HA until the Pi is fully booted. Still having problems, but now i'm getting this in the HA logs:

16-01-24 18:37:40 homeassistant.core: BusHandler:Exception doing job
Traceback (most recent call last):
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/core.py", line 829, in job_handler
    func(arg)
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/core.py", line 302, in onetime_listener
    listener(event)
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/components/mysensors.py", line 104, in gw_start
    gateway.event_callback('persistence', node_id)
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/components/mysensors.py", line 226, in node_update
    callback(self, node_id)
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/components/mysensors.py", line 165, in mysensors_callback
    add_devices(devices[node_id])
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/helpers/entity_component.py", line 81, in add_entities
    entity.update_ha_state()
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/helpers/entity.py", line 108, in update_ha_state
    self.unit_of_measurement is not None:
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/components/sensor/mysensors.py", line 137, in unit_of_measurement
    self.value_type == self.gateway.const.SetReq.V_PERCENTAGE or \
  File "/usr/lib/python3.4/enum.py", line 255, in __getattr__
    raise AttributeError(name) from None
AttributeError: V_PERCENTAGE
16-01-24 18:37:43 mysensors.mysensors: Error decoding message from gateway, probably received bad byte.

martinhjelmare

@drock1985

I think you need to delete the persistence file and start over and present your sensors. You should do this while looking at the output from hass so you get feedback if it's working or not.

From the top of your log output there was a warning about bad byte from the gateway. The gateway started confirmation was never logged. You should see this before you start your sensors. If you don't get the gateway confirmation, try restarting home assistant.

Also in the log, the persistence file loaded sensor 2 and 3, but some data was missing, probably. There was a note about missing sketch name for one of the sensors.

You need to present the sensors properly, including sketch name and then send one value per sensor before they show up in the gui.

asm

Hey @drock1985 , would you please update the thread if you are able to work around this ? I am experiencing something similar after my latest upgrade: None of the sensors appear in the UI. I have disabled persistence and removed the json files, and presented the nodes again, but still nothing appears. In debug mode I can see the messages from the Humidity sensor, for instance, but that's all.

drock1985

Hi @asm and @martinhjelmare

Still having issues after multiple restarts and two different nano's and NRF radios. I am like you, can see the messages but whatever I try I can't get rid of the bad byte error in the beginning.

Going to try a uno next and see if that changes anything.

martinhjelmare

@drock1985

What version of pyserial do you have installed?

pip show pyserial

Try version 2.5, which has been the least buggy for me.

pip3 install pyserial==2.5

pip show will not output anything if you have version 2.5 of pyserial installed.

drock1985

@martinhjelmare said:

pip3 install pyserial==2.5

I have 2.7 installed right now; installing 2.5 and i'll reboot and see what happens.

drock1985

Hi @martinhjelmare

I have installed pyserial2.5, but I still get errors. This is the latest when using an Arduino Uno (not genuine, but pretty close) and I do not see the unexpected byte messages anymore. Still not getting anything to show though.

 hass
Config directory: /home/pi/.homeassistant
WARNING:homeassistant.bootstrap:Colorlog package not found, console coloring dis                                                             abled
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=homeas                                                             sistant, service=turn_off>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=homeas                                                             sistant, service=turn_on>
INFO:homeassistant.bootstrap:Home Assistant core initialized
INFO:homeassistant.loader:Loaded logbook from homeassistant.components.logbook
INFO:homeassistant.loader:Loaded recorder from homeassistant.components.recorder
INFO:homeassistant.loader:Loaded http from homeassistant.components.http
INFO:homeassistant.loader:Loaded sun from homeassistant.components.sun
INFO:homeassistant.loader:Loaded updater from homeassistant.components.updater
INFO:homeassistant.loader:Loaded discovery from homeassistant.components.discove                                                             ry
INFO:homeassistant.loader:Loaded camera from homeassistant.components.camera
INFO:homeassistant.loader:Loaded frontend from homeassistant.components.frontend
INFO:homeassistant.loader:Loaded api from homeassistant.components.api
INFO:homeassistant.loader:Loaded history from homeassistant.components.history
INFO:homeassistant.loader:Loaded mysensors from homeassistant.components.mysenso                                                             rs
INFO:homeassistant.loader:Loaded conversation from homeassistant.components.conv                                                             ersation
INFO:homeassistant.loader:Loaded media_player from homeassistant.components.medi                                                             a_player
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=recor                                                             der>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=http>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=logbo                                                             ok>
INFO:urllib3.connectionpool:Starting new HTTP connection (1): maps.googleapis.co                                                             m
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state s                                                             un.sun=below_horizon; elevation=-12.86, next_setting=21:17:57 26-01-2016, friend                                                             ly_name=Sun, next_rising=11:42:09 26-01-2016 @ 18:28:34 25-01-2016>, entity_id=s                                                             un.sun>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=sun>
INFO:urllib3.connectionpool:Starting new HTTPS connection (1): pypi.python.org
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=updat                                                             er>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=disco                                                             very>
INFO:homeassistant.loader:Loaded camera.mjpeg from homeassistant.components.came                                                             ra.mjpeg
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state c                                                             amera.livingroom_camera=idle; friendly_name=LivingRoom Camera, entity_picture=/a                                                             pi/camera_proxy/camera.livingroom_camera?time=1453760914.813284 @ 18:28:34 25-01                                                             -2016>, entity_id=camera.livingroom_camera>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=camer                                                             a>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=api>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=front                                                             end>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=histo                                                             ry>
INFO:mysensors.mysensors:File does not exist or is not readable: /home/pi/.homea                                                             ssistant/mysensors1.pickle
INFO:homeassistant.loader:Loaded sensor from homeassistant.components.sensor
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=senso                                                             r>
INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: service=myse                                                             nsors.sensors, discovered=>
INFO:homeassistant.loader:Loaded switch from homeassistant.components.switch
INFO:homeassistant.loader:Loaded sensor.mysensors from homeassistant.components.                                                             sensor.mysensors
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=switch                                                             , service=turn_off>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=switch                                                             , service=turn_on>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=switc                                                             h>
INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: service=myse                                                             nsors.switches, discovered=>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=mysen                                                             sors>
INFO:homeassistant.loader:Loaded switch.mysensors from homeassistant.components.                                                             switch.mysensors
/home/pi/.homeassistant/lib/fuzzywuzzy/fuzz.py:33: UserWarning: Using slow pure-                                                             python SequenceMatcher. Install python-Levenshtein to remove this warning
  warnings.warn('Using slow pure-python SequenceMatcher. Install python-Levensht                                                             ein to remove this warning')
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=conver                                                             sation, service=process>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=conve                                                             rsation>
INFO:homeassistant.loader:Loaded media_player.squeezebox from homeassistant.comp                                                             onents.media_player.squeezebox
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state m                                                             edia_player.airspeaker=off; friendly_name=AirSpeaker, supported_media_commands=4                                                             47 @ 18:28:35 25-01-2016>, entity_id=media_player.airspeaker>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_                                                             player, service=media_next_track>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_                                                             player, service=media_play>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_                                                             player, service=volume_up>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_                                                             player, service=media_pause>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_                                                             player, service=turn_on>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_                                                             player, service=turn_off>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_                                                             player, service=volume_down>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_                                                             player, service=media_play_pause>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_                                                             player, service=play_media>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_                                                             player, service=media_previous_track>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_                                                             player, service=volume_set>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_                                                             player, service=volume_mute>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_                                                             player, service=media_seek>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_                                                             player, service=start_fireplace>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_                                                             player, service=start_epic_sax>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_                                                             player, service=play_youtube_video>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_                                                             player, service=play_media>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=media                                                             _player>
INFO:homeassistant.core:Starting Home Assistant (17 threads)
INFO:homeassistant.core:Bus:Handling <Event homeassistant_start[L]>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=homeas                                                             sistant, service=stop>
INFO:mysensors.mysensors:Trying to connect to /dev/ttyUSB0
INFO:homeassistant.components.http:Starting web interface at http://0.0.0.0:8123
INFO:homeassistant.core:Timer:starting
INFO:homeassistant.components.http:"GET /api/stream?api_password=no_password_set                                                             &restrict=state_changed,component_loaded,service_registered HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /api/bootstrap HTTP/1.1" 200 -
INFO:netdisco.service:Scanning
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.117
INFO:mysensors.mysensors:/dev/ttyUSB0 is open...
INFO:mysensors.mysensors:Connected to /dev/ttyUSB0
Exception in thread Thread-14:
Traceback (most recent call last):
  File "/usr/lib/python3.4/threading.py", line 920, in _bootstrap_inner
    self.run()
  File "/home/pi/.homeassistant/lib/mysensors/mysensors.py", line 312, in run
    response = self.handle_queue()
  File "/home/pi/.homeassistant/lib/mysensors/mysensors.py", line 222, in handle                                                             _queue
    reply = func(*args, **kwargs)
  File "/home/pi/.homeassistant/lib/mysensors/mysensors.py", line 105, in logic
    msg = Message(data)
  File "/home/pi/.homeassistant/lib/mysensors/mysensors.py", line 397, in __init                                                             __
    self.decode(data)
  File "/home/pi/.homeassistant/lib/mysensors/mysensors.py", line 417, in decode
    self.sub_type) = [int(f) for f in data]
  File "/home/pi/.homeassistant/lib/mysensors/mysensors.py", line 417, in <listc                                                             omp>
    self.sub_type) = [int(f) for f in data]
ValueError: invalid literal for int() with base 10: '\x00\x00\x00\x00\x00\x00\x0                                                             0\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0                                                             0\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x0                                                             0\x00\x00\x0

INFO:homeassistant.components.media_player:Updating media_player entities
INFO:homeassistant.components.http:"GET /api/error_log HTTP/1.1" 200 -
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.4
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.101
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.1
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.113
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.142
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.102
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.199
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.113
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:homeassistant.components.discovery:Found new service: philips_hue ('Philips                                                              hue (192.168.86.199)', 'http://192.168.86.199:80/')
INFO:homeassistant.loader:Loaded light from homeassistant.components.light
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=light,                                                              service=turn_on>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=light,                                                              service=turn_off>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=light                                                             >
INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: service=phil                                                             ips_hue, discovered=('Philips hue (192.168.86.199)', 'http://192.168.86.199:80/'                                                             )>
INFO:homeassistant.components.discovery:Found new service: belkin_wemo ('CatFan'                                                             , 'Socket', 'http://192.168.86.108:49153/setup.xml', '94103E30CDBC')
INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: service=belk                                                             in_wemo, discovered=('CatFan', 'Socket', 'http://192.168.86.108:49153/setup.xml'                                                             , '94103E30CDBC')>
INFO:homeassistant.components.discovery:Found new service: belkin_wemo ('LivingR                                                             oomLamp', 'Socket', 'http://192.168.86.163:49153/setup.xml', '94103E30C688')
INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: service=belk                                                             in_wemo, discovered=('LivingRoomLamp', 'Socket', 'http://192.168.86.163:49153/se                                                             tup.xml', '94103E30C688')>
INFO:homeassistant.components.discovery:Found new service: DLNA http://192.168.8                                                             6.113:9000/plugins/UPnP/MediaServer.xml
INFO:homeassistant.components.discovery:Found new service: DLNA http://192.168.8                                                             6.4:32469/DeviceDescription.xml
INFO:homeassistant.loader:Loaded light.hue from homeassistant.components.light.h                                                             ue
INFO:homeassistant.loader:Loaded switch.wemo from homeassistant.components.switc                                                             h.wemo
INFO:homeassistant.loader:Loaded switch.wemo from homeassistant.components.switc                                                             h.wemo
INFO:homeassistant.components.media_player:Updating media_player entities
INFO:phue:Attempting to connect to the bridge...
INFO:phue:Using ip: 192.168.86.199
INFO:phue:Using username from config: 30f609e6210320e41f0dfc32220a525f
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state l                                                             ight.aprils_lamp=off; friendly_name=April's lamp @ 18:28:50 25-01-2016>, entity_                                                             id=light.aprils_lamp>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state l                                                             ight.master_bedroom_3=off; friendly_name=Master bedroom 3 @ 18:28:50 25-01-2016>                                                             , entity_id=light.master_bedroom_3>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state l                                                             ight.master_bedroom_2=off; friendly_name=Master bedroom 2 @ 18:28:50 25-01-2016>                                                             , entity_id=light.master_bedroom_2>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state l                                                             ight.living_room_light_2=on; brightness=254, friendly_name=Living room light 2 @                                                              18:28:50 25-01-2016>, entity_id=light.living_room_light_2>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state l                                                             ight.master_bedroom_1=off; friendly_name=Master bedroom 1 @ 18:28:50 25-01-2016>                                                             , entity_id=light.master_bedroom_1>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state l                                                             ight.living_room_light_1=on; brightness=254, friendly_name=Living room light 1 @                                                              18:28:50 25-01-2016>, entity_id=light.living_room_light_1>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state l                                                             ight.master_bedroom_light_strip=off; friendly_name=Master bedroom light strip @                                                              18:28:50 25-01-2016>, entity_id=light.master_bedroom_light_strip>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state g                                                             roup.all_lights=unknown; friendly_name=all lights, entity_id=[], auto=True @ 18:                                                             28:50 25-01-2016>, entity_id=group.all_lights>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state g                                                             roup.all_lights=on; friendly_name=all lights, entity_id=('light.living_room_ligh                                                             t_1', 'light.aprils_lamp', 'light.master_bedroom_light_strip', 'light.living_roo                                                             m_light_2', 'light.master_bedroom_1', 'light.master_bedroom_2', 'light.master_be                                                             droom_3'), auto=True @ 18:28:50 25-01-2016>, entity_id=group.all_lights>
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:pywemo.subscribe:Listening on port 8989
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:homeassistant.components.discovery:Found new service: plex_mediaserver ('Ro                                                             cknet Server', 'https://192.168.86.4:32400')
INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: service=plex                                                             _mediaserver, discovered=('Rocknet Server', 'https://192.168.86.4:32400')>
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:homeassistant.components.discovery:Found new service: google_cast ('192.168                                                             .86.101', 8009)
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:homeassistant.loader:Loaded media_player.plex from homeassistant.components                                                             .media_player.plex
INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: service=goog                                                             le_cast, discovered=('192.168.86.101', 8009)>
INFO:homeassistant.components.discovery:Found new service: google_cast ('192.168                                                             .86.142', 8009)
INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: service=goog                                                             le_cast, discovered=('192.168.86.142', 8009)>
INFO:homeassistant.components.discovery:Found new service: google_cast ('192.168                                                             .86.102', 8009)
INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: service=goog                                                             le_cast, discovered=('192.168.86.102', 8009)>
INFO:homeassistant.loader:Loaded media_player.cast from homeassistant.components                                                             .media_player.cast
INFO:homeassistant.loader:Loaded media_player.cast from homeassistant.components                                                             .media_player.cast
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:pywemo.subscribe:Subscribing to events from <WeMo Switch "CatFan">
INFO:pywemo.subscribe:Resubscribe for <WeMo Switch "CatFan">
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:pywemo.subscribe:Subscribing to events from <WeMo Switch "LivingRoomLamp">
INFO:pywemo.subscribe:Resubscribe for <WeMo Switch "LivingRoomLamp">
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state s                                                             witch.catfan=off; friendly_name=CatFan @ 18:28:51 25-01-2016>, entity_id=switch.                                                             catfan>
INFO:pywemo.subscribe:Received event from <WeMo Switch "CatFan">(192.168.86.108)
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state g                                                             roup.all_switches=unknown; friendly_name=all switches, entity_id=[], auto=True @                                                              18:28:51 25-01-2016>, entity_id=group.all_switches>
INFO:homeassistant.components.switch.wemo:Subscription update for  <WeMo Switch                                                              "CatFan">
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state g                                                             roup.all_switches=off; friendly_name=all switches, entity_id=('switch.catfan',),                                                              auto=True @ 18:28:52 25-01-2016>, entity_id=group.all_switches>
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state s                                                             witch.livingroomlamp=on; friendly_name=LivingRoomLamp @ 18:28:52 25-01-2016>, en                                                             tity_id=switch.livingroomlamp>
INFO:pywemo.subscribe:Received event from <WeMo Switch "LivingRoomLamp">(192.168                                                             .86.163)
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state g                                                             roup.all_switches=on; friendly_name=all switches, entity_id=('switch.catfan', 's                                                             witch.livingroomlamp'), auto=True @ 18:28:52 25-01-2016>, entity_id=group.all_sw                                                             itches>
INFO:plexapi:GET http://192.168.86.4:32400/
INFO:homeassistant.components.switch.wemo:Subscription update for  <WeMo Switch                                                              "LivingRoomLamp">
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.4
INFO:homeassistant.components.media_player.plex:Connected to: htts://192.168.86.                                                             4:32400
INFO:plexapi:GET http://192.168.86.4:32400/clients
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.4
INFO:plexapi:GET http://192.168.86.4:32400/status/sessions
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.4
INFO:pychromecast:Querying device status
INFO:pychromecast:Querying device status
INFO:pychromecast:Querying device status
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.101
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.102
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.142
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state m                                                             edia_player.man_cave=off; friendly_name=Man Cave, supported_media_commands=1021                                                              @ 18:28:52 25-01-2016>, entity_id=media_player.man_cave>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state m                                                             edia_player.master_bedroom=off; friendly_name=Master Bedroom, supported_media_co                                                             mmands=1021 @ 18:28:52 25-01-2016>, entity_id=media_player.master_bedroom>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state m                                                             edia_player.living_room=off; friendly_name=Living Room, supported_media_commands                                                             =1021 @ 18:28:53 25-01-2016>, entity_id=media_player.living_room>
^CINFO:homeassistant.core:Stopping
INFO:homeassistant.core:Bus:Handling <Event homeassistant_stop[L]>
INFO:homeassistant.components.switch.wemo:Shutting down subscriptions.
INFO:mysensors.mysensors:Disconnecting from /dev/ttyUSB0
INFO:mysensors.mysensors:Stopping thread
INFO:homeassistant.components.recorder:Closing database
pi@home-assistant:~ $

drock1985

Ok, here is a better log. I decided to set up a new node, one that would auto get the node_ID to see if there was atleast some communication both ways. There seems to be, as the gateway did assign a node_ID, but it didn't show up in HA on the web at all.

Maybe I need to start HA all over again, just keep the configuration.yaml file?


pi@home-assistant:~/.homeassistant $ hass
Config directory: /home/pi/.homeassistant
WARNING:homeassistant.bootstrap:Colorlog package not found, console coloring disabled
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=homeassistant, service=turn_off>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=homeassistant, service=turn_on>
INFO:homeassistant.bootstrap:Home Assistant core initialized
INFO:homeassistant.loader:Loaded logbook from homeassistant.components.logbook
INFO:homeassistant.loader:Loaded recorder from homeassistant.components.recorder
INFO:homeassistant.loader:Loaded http from homeassistant.components.http
INFO:homeassistant.loader:Loaded history from homeassistant.components.history
INFO:homeassistant.loader:Loaded discovery from homeassistant.components.discovery
INFO:homeassistant.loader:Loaded camera from homeassistant.components.camera
INFO:homeassistant.loader:Loaded updater from homeassistant.components.updater
INFO:homeassistant.loader:Loaded sun from homeassistant.components.sun
INFO:homeassistant.loader:Loaded frontend from homeassistant.components.frontend
INFO:homeassistant.loader:Loaded api from homeassistant.components.api
INFO:homeassistant.loader:Loaded media_player from homeassistant.components.media_player
INFO:homeassistant.loader:Loaded mysensors from homeassistant.components.mysensors
INFO:homeassistant.loader:Loaded conversation from homeassistant.components.conversation
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=recorder>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=http>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=logbook>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=history>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=discovery>
INFO:homeassistant.loader:Loaded camera.mjpeg from homeassistant.components.camera.mjpeg
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state camera.livingroom_camera=idle; entity_picture=/api/camera_proxy/camera.livingroom_camera?time=1453761305.5420914, friendly_name=LivingRoom Camera @ 18:35:05 25-01-2016>, entity_id=camera.livingroom_camera>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=camera>
INFO:urllib3.connectionpool:Starting new HTTPS connection (1): pypi.python.org
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=updater>
INFO:urllib3.connectionpool:Starting new HTTP connection (1): maps.googleapis.com
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state sun.sun=below_horizon; friendly_name=Sun, next_rising=11:42:09 26-01-2016, next_setting=21:17:57 26-01-2016, elevation=-14.01 @ 18:35:11 25-01-2016>, entity_id=sun.sun>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=sun>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=api>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=frontend>
INFO:homeassistant.loader:Loaded media_player.squeezebox from homeassistant.components.media_player.squeezebox
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state media_player.airspeaker=off; supported_media_commands=447, friendly_name=AirSpeaker @ 18:35:11 25-01-2016>, entity_id=media_player.airspeaker>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=media_play>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=media_pause>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=turn_on>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=play_media>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=turn_off>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=volume_up>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=volume_down>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=media_play_pause>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=media_next_track>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=media_previous_track>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=volume_set>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=volume_mute>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=media_seek>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=start_fireplace>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=start_epic_sax>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=play_youtube_video>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=media_player, service=play_media>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=media_player>
INFO:mysensors.mysensors:File does not exist or is not readable: /home/pi/.homeassistant/mysensors1.pickle
INFO:homeassistant.loader:Loaded sensor from homeassistant.components.sensor
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=sensor>
INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: discovered=, service=mysensors.sensors>
INFO:homeassistant.loader:Loaded switch from homeassistant.components.switch
INFO:homeassistant.loader:Loaded sensor.mysensors from homeassistant.components.sensor.mysensors
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=switch, service=turn_off>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=switch, service=turn_on>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=switch>
INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: discovered=, service=mysensors.switches>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=mysensors>
INFO:homeassistant.loader:Loaded switch.mysensors from homeassistant.components.switch.mysensors
/home/pi/.homeassistant/lib/fuzzywuzzy/fuzz.py:33: UserWarning: Using slow pure-python SequenceMatcher. Install python-Levenshtein to remove this warning
  warnings.warn('Using slow pure-python SequenceMatcher. Install python-Levenshtein to remove this warning')
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=conversation, service=process>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=conversation>
INFO:homeassistant.core:Starting Home Assistant (17 threads)
INFO:homeassistant.core:Bus:Handling <Event homeassistant_start[L]>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=homeassistant, service=stop>
INFO:mysensors.mysensors:Trying to connect to /dev/ttyUSB0
INFO:homeassistant.components.http:Starting web interface at http://0.0.0.0:8123
INFO:homeassistant.core:Timer:starting
INFO:homeassistant.components.http:"GET /api/stream?api_password=no_password_set&restrict=state_changed,component_loaded,service_registered HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /api/bootstrap HTTP/1.1" 200 -
INFO:netdisco.service:Scanning
WARNING:homeassistant.components.recorder:Found unfinished sessions
INFO:mysensors.mysensors:/dev/ttyUSB0 is open...
INFO:mysensors.mysensors:Connected to /dev/ttyUSB0
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:gateway started, id=0, parent=0, distance=0
INFO:homeassistant.components.media_player:Updating media_player entities
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.4
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.102
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.1
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.199
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.113
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.101
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.113
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:homeassistant.components.discovery:Found new service: DLNA http://192.168.86.4:32469/DeviceDescription.xml
INFO:homeassistant.components.discovery:Found new service: DLNA http://192.168.86.113:9000/plugins/UPnP/MediaServer.xml
INFO:homeassistant.components.discovery:Found new service: plex_mediaserver ('Rocknet Server', 'https://192.168.86.4:32400')
INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: discovered=('Rocknet Server', 'https://192.168.86.4:32400'), service=plex_mediaserver>
INFO:homeassistant.components.discovery:Found new service: google_cast ('192.168.86.102', 8009)
INFO:homeassistant.loader:Loaded media_player.plex from homeassistant.components.media_player.plex
INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: discovered=('192.168.86.102', 8009), service=google_cast>
INFO:homeassistant.components.discovery:Found new service: google_cast ('192.168.86.142', 8009)
INFO:homeassistant.loader:Loaded media_player.cast from homeassistant.components.media_player.cast
INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: discovered=('192.168.86.142', 8009), service=google_cast>
INFO:homeassistant.components.discovery:Found new service: google_cast ('192.168.86.101', 8009)
INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: discovered=('192.168.86.101', 8009), service=google_cast>
INFO:homeassistant.components.discovery:Found new service: belkin_wemo ('Christmas Tree Lights', 'Insight', 'http://192.168.86.111:49153/setup.xml', '94103E395F2C')
INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: discovered=('Christmas Tree Lights', 'Insight', 'http://192.168.86.111:49153/setup.xml', '94103E395F2C'), service=belkin_wemo>
INFO:homeassistant.components.discovery:Found new service: belkin_wemo ('LivingRoomLamp', 'Socket', 'http://192.168.86.163:49153/setup.xml', '94103E30C688')
INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: discovered=('LivingRoomLamp', 'Socket', 'http://192.168.86.163:49153/setup.xml', '94103E30C688'), service=belkin_wemo>
INFO:homeassistant.components.discovery:Found new service: belkin_wemo ('CatFan', 'Socket', 'http://192.168.86.108:49153/setup.xml', '94103E30CDBC')
INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: discovered=('CatFan', 'Socket', 'http://192.168.86.108:49153/setup.xml', '94103E30CDBC'), service=belkin_wemo>
INFO:homeassistant.components.discovery:Found new service: philips_hue ('Philips hue (192.168.86.199)', 'http://192.168.86.199:80/')
INFO:homeassistant.loader:Loaded switch.wemo from homeassistant.components.switch.wemo
INFO:homeassistant.loader:Loaded switch.wemo from homeassistant.components.switch.wemo
INFO:homeassistant.loader:Loaded switch.wemo from homeassistant.components.switch.wemo
INFO:homeassistant.loader:Loaded light from homeassistant.components.light
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=light, service=turn_on>
INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: domain=light, service=turn_off>
INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=light>
INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: discovered=('Philips hue (192.168.86.199)', 'http://192.168.86.199:80/'), service=philips_hue>
INFO:homeassistant.loader:Loaded light.hue from homeassistant.components.light.hue
INFO:phue:Attempting to connect to the bridge...
INFO:phue:Using ip: 192.168.86.199
INFO:phue:Using username from config: 30f609e6210320e41f0dfc32220a525f
INFO:plexapi:GET http://192.168.86.4:32400/
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.4
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state light.master_bedroom_1=off; friendly_name=Master bedroom 1 @ 18:35:27 25-01-2016>, entity_id=light.master_bedroom_1>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state light.aprils_lamp=off; friendly_name=April's lamp @ 18:35:27 25-01-2016>, entity_id=light.aprils_lamp>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state light.master_bedroom_2=off; friendly_name=Master bedroom 2 @ 18:35:27 25-01-2016>, entity_id=light.master_bedroom_2>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state light.master_bedroom_light_strip=off; friendly_name=Master bedroom light strip @ 18:35:27 25-01-2016>, entity_id=light.master_bedroom_light_strip>
INFO:homeassistant.components.media_player.plex:Connected to: htts://192.168.86.4:32400
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state light.living_room_light_2=on; brightness=254, friendly_name=Living room light 2 @ 18:35:27 25-01-2016>, entity_id=light.living_room_light_2>
INFO:plexapi:GET http://192.168.86.4:32400/clients
INFO:pywemo.subscribe:Listening on port 8989
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state light.master_bedroom_3=off; friendly_name=Master bedroom 3 @ 18:35:27 25-01-2016>, entity_id=light.master_bedroom_3>
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.4
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state light.living_room_light_1=on; brightness=254, friendly_name=Living room light 1 @ 18:35:27 25-01-2016>, entity_id=light.living_room_light_1>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state group.all_lights=unknown; entity_id=[], auto=True, friendly_name=all lights @ 18:35:27 25-01-2016>, entity_id=group.all_lights>
INFO:plexapi:GET http://192.168.86.4:32400/status/sessions
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state group.all_lights=on; entity_id=('light.aprils_lamp', 'light.master_bedroom_1', 'light.living_room_light_1', 'light.living_room_light_2', 'light.master_bedroom_light_strip', 'light.master_bedroom_3', 'light.master_bedroom_2'), auto=True, friendly_name=all lights @ 18:35:27 25-01-2016>, entity_id=group.all_lights>
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.4
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
ERROR:homeassistant.components.media_player:Error while setting up platform cast
Traceback (most recent call last):
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/helpers/entity_component.py", line 145, in _setup_platform
    self.hass, platform_config, self.add_entities, discovery_info)
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/components/media_player/cast.py", line 39, in setup_platform
    import pychromecast
  File "/home/pi/.homeassistant/lib/pychromecast/__init__.py", line 14, in <module>
    from . import socket_client
ImportError: cannot import name 'socket_client'
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
ERROR:homeassistant.components.media_player:Error while setting up platform cast
Traceback (most recent call last):
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/helpers/entity_component.py", line 145, in _setup_platform
    self.hass, platform_config, self.add_entities, discovery_info)
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/components/media_player/cast.py", line 39, in setup_platform
    import pychromecast
  File "/home/pi/.homeassistant/lib/pychromecast/__init__.py", line 14, in <module>
    from . import socket_client
ImportError: cannot import name 'socket_client'
ERROR:homeassistant.components.media_player:Error while setting up platform cast
Traceback (most recent call last):
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/helpers/entity_component.py", line 145, in _setup_platform
    self.hass, platform_config, self.add_entities, discovery_info)
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/components/media_player/cast.py", line 68, in setup_platform
    casts.append(CastDevice(host))
  File "/usr/local/lib/python3.4/dist-packages/homeassistant/components/media_player/cast.py", line 84, in __init__
    import pychromecast.controllers.youtube as youtube
AttributeError: 'module' object has no attribute 'controllers'
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:pywemo.subscribe:Subscribing to events from <WeMo Switch "CatFan">
INFO:pywemo.subscribe:Resubscribe for <WeMo Switch "CatFan">
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state switch.catfan=off; friendly_name=CatFan @ 18:35:29 25-01-2016>, entity_id=switch.catfan>
INFO:pywemo.subscribe:Received event from <WeMo Switch "CatFan">(192.168.86.108)
INFO:pywemo.subscribe:Subscribing to events from <WeMo Switch "LivingRoomLamp">
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state group.all_switches=unknown; entity_id=[], auto=True, friendly_name=all switches @ 18:35:29 25-01-2016>, entity_id=group.all_switches>
INFO:homeassistant.components.switch.wemo:Subscription update for  <WeMo Switch "CatFan">
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state group.all_switches=off; entity_id=('switch.catfan',), auto=True, friendly_name=all switches @ 18:35:29 25-01-2016>, entity_id=group.all_switches>
INFO:pywemo.subscribe:Subscribing to events from <WeMo Insight "Christmas Tree Lights">
INFO:pywemo.subscribe:Resubscribe for <WeMo Switch "LivingRoomLamp">
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:pywemo.subscribe:Resubscribe for <WeMo Insight "Christmas Tree Lights">
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state switch.livingroomlamp=on; friendly_name=LivingRoomLamp @ 18:35:29 25-01-2016>, entity_id=switch.livingroomlamp>
INFO:pywemo.subscribe:Received event from <WeMo Switch "LivingRoomLamp">(192.168.86.163)
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state group.all_switches=on; entity_id=('switch.livingroomlamp', 'switch.catfan'), auto=True, friendly_name=all switches @ 18:35:29 25-01-2016>, entity_id=group.all_switches>
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:homeassistant.components.switch.wemo:Subscription update for  <WeMo Switch "LivingRoomLamp">
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state switch.christmas_tree_lights=off; friendly_name=Christmas Tree Lights @ 18:35:29 25-01-2016>, entity_id=switch.christmas_tree_lights>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state group.all_switches=on; entity_id=('switch.livingroomlamp', 'switch.christmas_tree_lights', 'switch.catfan'), auto=True, friendly_name=all switches @ 18:35:29 25-01-2016>, entity_id=group.all_switches>
INFO:pywemo.subscribe:Received event from <WeMo Insight "Christmas Tree Lights">(192.168.86.111)
INFO:homeassistant.components.switch.wemo:Subscription update for  <WeMo Insight "Christmas Tree Lights">
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:homeassistant.components.media_player:Updating media_player entities
INFO:homeassistant.components.light:Updating light entities
INFO:homeassistant.components.camera:Updating camera entities
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state camera.livingroom_camera=idle; entity_picture=/api/camera_proxy/camera.livingroom_camera?time=1453761330.5161493, friendly_name=LivingRoom Camera @ 18:35:05 25-01-2016>, entity_id=camera.livingroom_camera, old_state=<state camera.livingroom_camera=idle; entity_picture=/api/camera_proxy/camera.livingroom_camera?time=1453761305.5420914, friendly_name=LivingRoom Camera @ 18:35:05 25-01-2016>>
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state sun.sun=below_horizon; friendly_name=Sun, next_rising=11:42:09 26-01-2016, next_setting=21:17:57 26-01-2016, elevation=-14.07 @ 18:35:11 25-01-2016>, entity_id=sun.sun, old_state=<state sun.sun=below_horizon; friendly_name=Sun, next_rising=11:42:09 26-01-2016, next_setting=21:17:57 26-01-2016, elevation=-14.01 @ 18:35:11 25-01-2016>>
INFO:pywemo.subscribe:Received event from <WeMo Insight "Christmas Tree Lights">(192.168.86.111)
INFO:homeassistant.components.switch.wemo:Subscription update for  <WeMo Insight "Christmas Tree Lights">
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
INFO:homeassistant.components.media_player:Updating media_player entities
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 1-1-0 s=255,c=0,t=17,pt=0,l=5,sg=0:1.5.1
INFO:homeassistant.components.mysensors:update sensor_update: node 1
INFO:homeassistant.components.mysensors:No sketch_name: node 1
INFO:homeassistant.components.mysensors:No sketch_name: node 1
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 1-1-0 s=255,c=3,t=6,pt=1,l=1,sg=0:0
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:send: 0-0-1-1 s=255,c=3,t=6,pt=0,l=1,sg=0,st=ok:M
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 1-1-0 s=255,c=3,t=11,pt=0,l=13,sg=0:Motion Sensor
INFO:homeassistant.components.mysensors:update sensor_update: node 1
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 1-1-0 s=255,c=3,t=12,pt=0,l=3,sg=0:1.0
INFO:homeassistant.components.mysensors:update sensor_update: node 1
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 1-1-0 s=1,c=0,t=1,pt=0,l=0,sg=0:
INFO:homeassistant.components.mysensors:update sensor_update: node 1
INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 1-1-0 s=1,c=1,t=16,pt=0,l=1,sg=0:1
INFO:homeassistant.components.mysensors:update sensor_update: node 1
INFO:homeassistant.components.media_player:Updating media_player entities
INFO:homeassistant.components.http:"GET /api/error_log HTTP/1.1" 200 -
INFO:homeassistant.components.media_player:Updating media_player entities
INFO:homeassistant.components.light:Updating light entities
INFO:homeassistant.components.camera:Updating camera entities
INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: new_state=<state camera.livingroom_camera=idle; entity_picture=/api/camera_proxy/camera.livingroom_camera?time=1453761360.5125034, friendly_name=LivingRoom Camera @ 18:35:05 25-01-2016>, entity_id=camera.livingroom_camera, old_state=<state camera.livingroom_camera=idle; entity_picture=/api/camera_proxy/camera.livingroom_camera?time=1453761330.5161493, friendly_name=LivingRoom Camera @ 18:35:05 25-01-2016>>
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.117
INFO:homeassistant.components.media_player:Updating media_player entities
INFO:homeassistant.components.http:"GET /states HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /static/frontend-1003c31441ec44b3db84b49980f736a7.html HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /static/favicon-192x192.png HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /static/favicon.ico HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /api/bootstrap HTTP/1.1" 200 -
INFO:homeassistant.components.http:"GET /api/stream?api_password=no_password_set&restrict=state_changed,component_loaded,service_registered HTTP/1.1" 200 -
INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.117
INFO:homeassistant.components.http:"GET /api/error_log HTTP/1.1" 200 -
^CINFO:homeassistant.core:Stopping
INFO:homeassistant.core:Bus:Handling <Event homeassistant_stop[L]>
INFO:homeassistant.components.switch.wemo:Shutting down subscriptions.
INFO:mysensors.mysensors:Disconnecting from /dev/ttyUSB0
INFO:mysensors.mysensors:Stopping thread
INFO:homeassistant.components.recorder:Closing database
pi@home-assistant:~/.homeassistant $

asm

@drock1985 Interesting ! I restarted a few times too with persistence enabled and at some point the json file gets populated. Another restart and the sensor appears in the UI ...

drock1985

Hi @asm

Glad to hear you had some luck. I'm getting closer (i think), getting different errors in the log now. The Motion sensor shows up in terminal, and even identifies it just not in the GUI.

ARG! I bet it is something so small it's not funny anymore!

Jan 25 19:03:23 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTPS connection (1): pypi.python.org
Jan 25 19:03:24 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=updater>
Jan 25 19:03:24 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=history>
Jan 25 19:03:24 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): maps.googleapis.com
Jan 25 19:03:24 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: entity_id=sun.sun, new_state=<state sun.sun=below_horizon; elevation=-18.97, next_setting=21:17:57 26-01-2016, friendly_name=Sun, next_rising=11:42:09 26-01-2016 @ 19:03:24 25-01-2016>>
Jan 25 19:03:24 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=sun>
Jan 25 19:03:24 home-assistant hass[1416]: INFO:homeassistant.loader:Loaded sensor from homeassistant.components.sensor
Jan 25 19:03:24 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=sensor>
Jan 25 19:03:24 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: service=mysensors.sensors, discovered=>
Jan 25 19:03:24 home-assistant hass[1416]: INFO:homeassistant.loader:Loaded sensor.mysensors from homeassistant.components.sensor.mysensors
Jan 25 19:03:24 home-assistant hass[1416]: INFO:homeassistant.loader:Loaded switch from homeassistant.components.switch
Jan 25 19:03:24 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: service=turn_off, domain=switch>
Jan 25 19:03:24 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: service=turn_on, domain=switch>
Jan 25 19:03:24 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=switch>
Jan 25 19:03:24 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: service=mysensors.switches, discovered=>
Jan 25 19:03:24 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=mysensors>
Jan 25 19:03:24 home-assistant hass[1416]: INFO:homeassistant.loader:Loaded switch.mysensors from homeassistant.components.switch.mysensors
Jan 25 19:03:24 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=discovery>
Jan 25 19:03:24 home-assistant hass[1416]: INFO:homeassistant.core:Starting Home Assistant (17 threads)
Jan 25 19:03:24 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event homeassistant_start[L]>
Jan 25 19:03:24 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: service=stop, domain=homeassistant>
Jan 25 19:03:24 home-assistant hass[1416]: INFO:homeassistant.components.http:Starting web interface at http://0.0.0.0:8123
Jan 25 19:03:24 home-assistant hass[1416]: INFO:homeassistant.core:Timer:starting
Jan 25 19:03:24 home-assistant hass[1416]: INFO:mysensors.mysensors:Trying to connect to /dev/ttyUSB0
Jan 25 19:03:24 home-assistant hass[1416]: INFO:homeassistant.components.mysensors:update persistence: node 1
Jan 25 19:03:24 home-assistant hass[1416]: INFO:homeassistant.components.mysensors:No sketch_name: node 1
Jan 25 19:03:24 home-assistant hass[1416]: INFO:homeassistant.components.http:"GET /api/stream?api_password=no_password_set&restrict=state_changed,component_loaded,service_registered HTTP/1.1" 200 -
Jan 25 19:03:24 home-assistant hass[1416]: INFO:homeassistant.components.mysensors:No sketch_name: node 1
Jan 25 19:03:24 home-assistant hass[1416]: INFO:netdisco.service:Scanning
Jan 25 19:03:24 home-assistant hass[1416]: INFO:homeassistant.components.http:"GET /api/bootstrap HTTP/1.1" 200 -
Jan 25 19:03:27 home-assistant hass[1416]: INFO:mysensors.mysensors:/dev/ttyUSB0 is open...
Jan 25 19:03:27 home-assistant hass[1416]: INFO:mysensors.mysensors:Connected to /dev/ttyUSB0
Jan 25 19:03:27 home-assistant hass[1416]: INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:gateway started, id=0, parent=0, distance=0
Jan 25 19:03:30 home-assistant hass[1416]: INFO:homeassistant.components.media_player:Updating media_player entities
Jan 25 19:03:30 home-assistant hass[1416]: INFO:homeassistant.components.camera:Updating camera entities
Jan 25 19:03:30 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: old_state=<state camera.livingroom_camera=idle; entity_picture=/api/camera_proxy/camera.livingroom_camera?time=1453763003.501535, friendly_name=LivingRoom Camera @ 19:03:23 25-01-2016>, entity_id=camera.livingroom_camera, new_state=<state camera.livingroom_camera=idle; entity_picture=/api/camera_proxy/camera.livingroom_camera?time=1453763010.532438, friendly_name=LivingRoom Camera @ 19:03:23 25-01-2016>>
Jan 25 19:03:30 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: old_state=<state sun.sun=below_horizon; elevation=-18.97, next_setting=21:17:57 26-01-2016, friendly_name=Sun, next_rising=11:42:09 26-01-2016 @ 19:03:24 25-01-2016>, entity_id=sun.sun, new_state=<state sun.sun=below_horizon; elevation=-18.99, next_setting=21:17:57 26-01-2016, friendly_name=Sun, next_rising=11:42:09 26-01-2016 @ 19:03:24 25-01-2016>>
Jan 25 19:03:37 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.1
Jan 25 19:03:37 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.101
Jan 25 19:03:37 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.113
Jan 25 19:03:38 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.142
Jan 25 19:03:38 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.199
Jan 25 19:03:38 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.113
Jan 25 19:03:38 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.102
Jan 25 19:03:38 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.108
Jan 25 19:03:38 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
Jan 25 19:03:38 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.111
Jan 25 19:03:38 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.4
Jan 25 19:03:38 home-assistant hass[1416]: INFO:homeassistant.components.discovery:Found new service: DLNA http://192.168.86.113:9000/plugins/UPnP/MediaServer.xml
Jan 25 19:03:39 home-assistant hass[1416]: INFO:homeassistant.components.discovery:Found new service: plex_mediaserver ('Rocknet Server', 'https://192.168.86.4:32400')
Jan 25 19:03:39 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: service=plex_mediaserver, discovered=('Rocknet Server', 'https://192.168.86.4:32400')>
Jan 25 19:03:39 home-assistant hass[1416]: INFO:homeassistant.components.discovery:Found new service: google_cast ('192.168.86.142', 8009)
Jan 25 19:03:39 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: service=google_cast, discovered=('192.168.86.142', 8009)>
Jan 25 19:03:39 home-assistant hass[1416]: INFO:homeassistant.components.discovery:Found new service: google_cast ('192.168.86.101', 8009)
Jan 25 19:03:39 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: service=google_cast, discovered=('192.168.86.101', 8009)>
Jan 25 19:03:39 home-assistant hass[1416]: INFO:homeassistant.components.discovery:Found new service: google_cast ('192.168.86.102', 8009)
Jan 25 19:03:39 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: service=google_cast, discovered=('192.168.86.102', 8009)>
Jan 25 19:03:39 home-assistant hass[1416]: INFO:homeassistant.components.discovery:Found new service: belkin_wemo ('LivingRoomLamp', 'Socket', 'http://192.168.86.163:49153/setup.xml', '94103E30C688')
Jan 25 19:03:39 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: service=belkin_wemo, discovered=('LivingRoomLamp', 'Socket', 'http://192.168.86.163:49153/setup.xml', '94103E30C688')>
Jan 25 19:03:39 home-assistant hass[1416]: INFO:homeassistant.loader:Loaded media_player.plex from homeassistant.components.media_player.plex
Jan 25 19:03:39 home-assistant hass[1416]: INFO:homeassistant.loader:Loaded media_player.cast from homeassistant.components.media_player.cast
Jan 25 19:03:39 home-assistant hass[1416]: INFO:homeassistant.loader:Loaded media_player.cast from homeassistant.components.media_player.cast
Jan 25 19:03:39 home-assistant hass[1416]: INFO:homeassistant.loader:Loaded media_player.cast from homeassistant.components.media_player.cast
Jan 25 19:03:39 home-assistant hass[1416]: INFO:homeassistant.components.discovery:Found new service: belkin_wemo ('Christmas Tree Lights', 'Insight', 'http://192.168.86.111:49153/setup.xml', '94103E395F2C')
Jan 25 19:03:39 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: service=belkin_wemo, discovered=('Christmas Tree Lights', 'Insight', 'http://192.168.86.111:49153/setup.xml', '94103E395F2C')>
Jan 25 19:03:39 home-assistant hass[1416]: INFO:homeassistant.loader:Loaded switch.wemo from homeassistant.components.switch.wemo
Jan 25 19:03:39 home-assistant hass[1416]: INFO:homeassistant.components.discovery:Found new service: belkin_wemo ('CatFan', 'Socket', 'http://192.168.86.108:49153/setup.xml', '94103E30CDBC')
Jan 25 19:03:39 home-assistant hass[1416]: INFO:homeassistant.loader:Loaded switch.wemo from homeassistant.components.switch.wemo
Jan 25 19:03:39 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: service=belkin_wemo, discovered=('CatFan', 'Socket', 'http://192.168.86.108:49153/setup.xml', '94103E30CDBC')>
Jan 25 19:03:39 home-assistant hass[1416]: INFO:homeassistant.components.discovery:Found new service: philips_hue ('Philips hue (192.168.86.199)', 'http://192.168.86.199:80/')
Jan 25 19:03:39 home-assistant hass[1416]: INFO:homeassistant.loader:Loaded light from homeassistant.components.light
Jan 25 19:03:40 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: service=turn_on, domain=light>
Jan 25 19:03:40 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event service_registered[L]: service=turn_off, domain=light>
Jan 25 19:03:40 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event component_loaded[L]: component=light>
Jan 25 19:03:40 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event platform_discovered[L]: service=philips_hue, discovered=('Philips hue (192.168.86.199)', 'http://192.168.86.199:80/')>
Jan 25 19:03:40 home-assistant hass[1416]: INFO:homeassistant.loader:Loaded light.hue from homeassistant.components.light.hue
Jan 25 19:03:40 home-assistant hass[1416]: INFO:phue:Attempting to connect to the bridge...
Jan 25 19:03:40 home-assistant hass[1416]: INFO:phue:Using ip: 192.168.86.199
Jan 25 19:03:40 home-assistant hass[1416]: INFO:phue:Using username from config: 30f609e6210320e41f0dfc32220a525f
Jan 25 19:03:40 home-assistant hass[1416]: INFO:plexapi:GET http://192.168.86.4:32400/
Jan 25 19:03:40 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.4
Jan 25 19:03:40 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: entity_id=light.master_bedroom_1, new_state=<state light.master_bedroom_1=off; friendly_name=Master bedroom 1 @ 19:03:40 25-01-2016>>
Jan 25 19:03:40 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: entity_id=light.master_bedroom_light_strip, new_state=<state light.master_bedroom_light_strip=off; friendly_name=Master bedroom light strip @ 19:03:40 25-01-2016>>
Jan 25 19:03:40 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: entity_id=light.master_bedroom_3, new_state=<state light.master_bedroom_3=off; friendly_name=Master bedroom 3 @ 19:03:40 25-01-2016>>
Jan 25 19:03:40 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: entity_id=light.master_bedroom_2, new_state=<state light.master_bedroom_2=off; friendly_name=Master bedroom 2 @ 19:03:40 25-01-2016>>
Jan 25 19:03:40 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: entity_id=light.living_room_light_1, new_state=<state light.living_room_light_1=on; friendly_name=Living room light 1, brightness=254 @ 19:03:40 25-01-2016>>
Jan 25 19:03:40 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: entity_id=light.aprils_lamp, new_state=<state light.aprils_lamp=on; friendly_name=April's lamp, brightness=254 @ 19:03:40 25-01-2016>>
Jan 25 19:03:40 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: entity_id=light.living_room_light_2, new_state=<state light.living_room_light_2=on; friendly_name=Living room light 2, brightness=254 @ 19:03:40 25-01-2016>>
Jan 25 19:03:40 home-assistant hass[1416]: INFO:homeassistant.components.media_player.plex:Connected to: htts://192.168.86.4:32400
Jan 25 19:03:40 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: entity_id=group.all_lights, new_state=<state group.all_lights=unknown; entity_id=[], auto=True, friendly_name=all lights @ 19:03:40 25-01-2016>>
Jan 25 19:03:40 home-assistant hass[1416]: INFO:plexapi:GET http://192.168.86.4:32400/clients
Jan 25 19:03:40 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: entity_id=group.all_lights, new_state=<state group.all_lights=on; entity_id=('light.master_bedroom_3', 'light.aprils_lamp', 'light.living_room_light_1', 'light.master_bedroom_1', 'light.master_bedroom_2', 'light.master_bedroom_light_strip', 'light.living_room_light_2'), auto=True, friendly_name=all lights @ 19:03:40 25-01-2016>>
Jan 25 19:03:40 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.4
Jan 25 19:03:40 home-assistant hass[1416]: INFO:plexapi:GET http://192.168.86.4:32400/status/sessions
Jan 25 19:03:40 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.4
Jan 25 19:03:40 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
Jan 25 19:03:40 home-assistant hass[1416]: ERROR:homeassistant.components.switch:Error while setting up platform wemo
Jan 25 19:03:40 home-assistant hass[1416]: Traceback (most recent call last):
Jan 25 19:03:40 home-assistant hass[1416]: File "/usr/local/lib/python3.4/dist-packages/homeassistant/helpers/entity_component.py", line 145, in _setup_platform
Jan 25 19:03:40 home-assistant hass[1416]: self.hass, platform_config, self.add_entities, discovery_info)
Jan 25 19:03:40 home-assistant hass[1416]: File "/usr/local/lib/python3.4/dist-packages/homeassistant/components/switch/wemo.py", line 25, in setup_platform
Jan 25 19:03:40 home-assistant hass[1416]: import pywemo.discovery as discovery
Jan 25 19:03:40 home-assistant hass[1416]: AttributeError: 'module' object has no attribute 'discovery'
Jan 25 19:03:40 home-assistant hass[1416]: INFO:pywemo.subscribe:Listening on port 8989
Jan 25 19:03:40 home-assistant hass[1416]: ERROR:homeassistant.components.switch:Error while setting up platform wemo
Jan 25 19:03:40 home-assistant hass[1416]: Traceback (most recent call last):
Jan 25 19:03:40 home-assistant hass[1416]: File "/usr/local/lib/python3.4/dist-packages/homeassistant/helpers/entity_component.py", line 145, in _setup_platform
Jan 25 19:03:40 home-assistant hass[1416]: self.hass, platform_config, self.add_entities, discovery_info)
Jan 25 19:03:40 home-assistant hass[1416]: File "/usr/local/lib/python3.4/dist-packages/homeassistant/components/switch/wemo.py", line 25, in setup_platform
Jan 25 19:03:40 home-assistant hass[1416]: import pywemo.discovery as discovery
Jan 25 19:03:40 home-assistant hass[1416]: AttributeError: 'module' object has no attribute 'discovery'
Jan 25 19:03:40 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
Jan 25 19:03:40 home-assistant hass[1416]: INFO:homeassistant.components.media_player:Updating media_player entities
Jan 25 19:03:40 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
Jan 25 19:03:40 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
Jan 25 19:03:40 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
Jan 25 19:03:40 home-assistant hass[1416]: INFO:pychromecast:Querying device status
Jan 25 19:03:40 home-assistant hass[1416]: INFO:pychromecast:Querying device status
Jan 25 19:03:40 home-assistant hass[1416]: INFO:pychromecast:Querying device status
Jan 25 19:03:40 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.102
Jan 25 19:03:40 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.101
Jan 25 19:03:40 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.142
Jan 25 19:03:40 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
Jan 25 19:03:40 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
Jan 25 19:03:40 home-assistant hass[1416]: INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 1-1-0 s=1,c=1,t=16,pt=0,l=1,sg=0:0
Jan 25 19:03:40 home-assistant hass[1416]: INFO:homeassistant.components.mysensors:update sensor_update: node 1
Jan 25 19:03:40 home-assistant hass[1416]: INFO:homeassistant.components.mysensors:No sketch_name: node 1
Jan 25 19:03:40 home-assistant hass[1416]: INFO:homeassistant.components.mysensors:No sketch_name: node 1
Jan 25 19:03:41 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: entity_id=media_player.man_cave, new_state=<state media_player.man_cave=off; supported_media_commands=1021, friendly_name=Man Cave @ 19:03:40 25-01-2016>>
Jan 25 19:03:41 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
Jan 25 19:03:41 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
Jan 25 19:03:41 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: entity_id=media_player.master_bedroom, new_state=<state media_player.master_bedroom=off; supported_media_commands=1021, friendly_name=Master Bedroom @ 19:03:41 25-01-2016>>
Jan 25 19:03:41 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
Jan 25 19:03:41 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
Jan 25 19:03:41 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
Jan 25 19:03:41 home-assistant hass[1416]: INFO:pywemo.subscribe:Subscribing to events from <WeMo Switch "LivingRoomLamp">
Jan 25 19:03:41 home-assistant hass[1416]: INFO:pywemo.subscribe:Resubscribe for <WeMo Switch "LivingRoomLamp">
Jan 25 19:03:41 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
Jan 25 19:03:41 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
Jan 25 19:03:41 home-assistant hass[1416]: INFO:pywemo.subscribe:Received event from <WeMo Switch "LivingRoomLamp">(192.168.86.163)
Jan 25 19:03:41 home-assistant hass[1416]: INFO:homeassistant.components.switch.wemo:Subscription update for  <WeMo Switch "LivingRoomLamp">
Jan 25 19:03:41 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: entity_id=switch.livingroomlamp, new_state=<state switch.livingroomlamp=on; friendly_name=LivingRoomLamp @ 19:03:41 25-01-2016>>
Jan 25 19:03:41 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: entity_id=group.all_switches, new_state=<state group.all_switches=unknown; entity_id=[], auto=True, friendly_name=all switches @ 19:03:41 25-01-2016>>
Jan 25 19:03:41 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: entity_id=group.all_switches, new_state=<state group.all_switches=on; entity_id=('switch.livingroomlamp',), auto=True, friendly_name=all switches @ 19:03:41 25-01-2016>>
Jan 25 19:03:41 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.163
Jan 25 19:03:41 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: entity_id=media_player.living_room, new_state=<state media_player.living_room=off; supported_media_commands=1021, friendly_name=Living Room @ 19:03:41 25-01-2016>>
Jan 25 19:03:45 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.117
Jan 25 19:03:50 home-assistant hass[1416]: INFO:homeassistant.components.media_player:Updating media_player entities
Jan 25 19:03:51 home-assistant hass[1416]: INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 1-1-0 s=255,c=0,t=17,pt=0,l=5,sg=0:1.5.1
Jan 25 19:03:51 home-assistant hass[1416]: INFO:homeassistant.components.mysensors:update sensor_update: node 1
Jan 25 19:03:51 home-assistant hass[1416]: INFO:homeassistant.components.mysensors:No sketch_name: node 1
Jan 25 19:03:51 home-assistant hass[1416]: INFO:homeassistant.components.mysensors:No sketch_name: node 1
Jan 25 19:03:51 home-assistant hass[1416]: INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 1-1-0 s=255,c=3,t=6,pt=1,l=1,sg=0:0
Jan 25 19:03:51 home-assistant hass[1416]: INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:send: 0-0-1-1 s=255,c=3,t=6,pt=0,l=1,sg=0,st=ok:M
Jan 25 19:03:53 home-assistant hass[1416]: INFO:mysensors.mysensors:n:0 c:0 t:3 s:9 p:read: 1-1-0 s=255,c=3,t=11,pt=0,l=13,sg=0:Motion Sensor
Jan 25 19:03:53 home-assistant hass[1416]: INFO:homeassistant.components.mysensors:update sensor_update: node 1
Jan 25 19:03:53 home-assistant hass[1416]: INFO:homeassistant.components.mysensors:adding new devices: [<Entity Motion Sensor 1.1: >]
Jan 25 19:04:00 home-assistant hass[1416]: Exception in thread Thread-15:
Jan 25 19:04:00 home-assistant hass[1416]: Traceback (most recent call last):
Jan 25 19:04:00 home-assistant hass[1416]: File "/usr/lib/python3.4/threading.py", line 920, in _bootstrap_inner
Jan 25 19:04:00 home-assistant hass[1416]: self.run()
Jan 25 19:04:00 home-assistant hass[1416]: File "/home/pi/.homeassistant/lib/mysensors/mysensors.py", line 312, in run
Jan 25 19:04:00 home-assistant hass[1416]: response = self.handle_queue()
Jan 25 19:04:00 home-assistant hass[1416]: File "/home/pi/.homeassistant/lib/mysensors/mysensors.py", line 222, in handle_queue
Jan 25 19:04:00 home-assistant hass[1416]: reply = func(*args, **kwargs)
Jan 25 19:04:00 home-assistant hass[1416]: File "/home/pi/.homeassistant/lib/mysensors/mysensors.py", line 112, in logic
Jan 25 19:04:00 home-assistant hass[1416]: return self._handle_internal(msg)
Jan 25 19:04:00 home-assistant hass[1416]: File "/home/pi/.homeassistant/lib/mysensors/mysensors.py", line 74, in _handle_internal
Jan 25 19:04:00 home-assistant hass[1416]: self.alert(msg.node_id)
Jan 25 19:04:00 home-assistant hass[1416]: File "/home/pi/.homeassistant/lib/mysensors/mysensors.py", line 175, in alert
Jan 25 19:04:00 home-assistant hass[1416]: self.event_callback("sensor_update", nid)
Jan 25 19:04:00 home-assistant hass[1416]: File "/usr/local/lib/python3.4/dist-packages/homeassistant/components/mysensors.py", line 226, in node_update
Jan 25 19:04:00 home-assistant hass[1416]: callback(self, node_id)
Jan 25 19:04:00 home-assistant hass[1416]: File "/usr/local/lib/python3.4/dist-packages/homeassistant/components/mysensors.py", line 165, in mysensors_callback
Jan 25 19:04:00 home-assistant hass[1416]: add_devices(devices[node_id])
Jan 25 19:04:00 home-assistant hass[1416]: File "/usr/local/lib/python3.4/dist-packages/homeassistant/helpers/entity_component.py", line 81, in add_entities
Jan 25 19:04:00 home-assistant hass[1416]: entity.update_ha_state()
Jan 25 19:04:00 home-assistant hass[1416]: File "/usr/local/lib/python3.4/dist-packages/homeassistant/helpers/entity.py", line 108, in update_ha_state
Jan 25 19:04:00 home-assistant hass[1416]: self.unit_of_measurement is not None:
Jan 25 19:04:00 home-assistant hass[1416]: File "/usr/local/lib/python3.4/dist-packages/homeassistant/components/sensor/mysensors.py", line 137, in unit_of_measurement
Jan 25 19:04:00 home-assistant hass[1416]: self.value_type == self.gateway.const.SetReq.V_PERCENTAGE or \
Jan 25 19:04:00 home-assistant hass[1416]: File "/usr/lib/python3.4/enum.py", line 255, in __getattr__
Jan 25 19:04:00 home-assistant hass[1416]: raise AttributeError(name) from None
Jan 25 19:04:00 home-assistant hass[1416]: AttributeError: V_PERCENTAGE
Jan 25 19:04:00 home-assistant hass[1416]: INFO:homeassistant.components.media_player:Updating media_player entities
Jan 25 19:04:00 home-assistant hass[1416]: INFO:homeassistant.components.light:Updating light entities
Jan 25 19:04:00 home-assistant hass[1416]: INFO:homeassistant.components.camera:Updating camera entities
Jan 25 19:04:00 home-assistant hass[1416]: INFO:homeassistant.core:Bus:Handling <Event state_changed[L]: old_state=<state camera.livingroom_camera=idle; entity_picture=/api/camera_proxy/camera.livingroom_camera?time=1453763010.532438, friendly_name=LivingRoom Camera @ 19:03:23 25-01-2016>, entity_id=camera.livingroom_camera, new_state=<state camera.livingroom_camera=idle; entity_picture=/api/camera_proxy/camera.livingroom_camera?time=1453763040.5191662, friendly_name=LivingRoom Camera @ 19:03:23 25-01-2016>>
Jan 25 19:04:00 home-assistant hass[1416]: INFO:urllib3.connectionpool:Starting new HTTP connection (1): 192.168.86.117

martinhjelmare

@drock1985

All the messages logged from the gateway look good in the latest log output without errors. Only thing missing is a log line saying "adding new devices...". There are three main reasons why this doesn't happen when the code executes:

A device with the same node id is already added.
Some node/sensor data is missing in the data stuctures, sketch name, child id, S_TYPE, V_TYPE.
The S_TYPE and V_TYPE for the child sensor is not handled by any of the platforms that home assistant supports.

All pure sensors in version 1.5 are currently supported by the sensor platform. The switch platform supports:

S_DOOR,
S_MOTION,
S_SMOKE,
S_LIGHT,
S_LOCK,
S_BINARY,
S_SPRINKLER,
S_WATER_LEAK,
S_SOUND,
S_VIBRATION,
S_MOISTURE,
V_ARMED,
V_LIGHT,
V_LOCK_STATUS,
V_STATUS

There's a bug if you don't specify version 1.5 in the config of home assistant. It's related to the V_PERCENTAGE attribute error. I'm fixing that now. But I don't think that's your problem here. My guess is point 1 or 2 above is the problem.

You could try the following:

Remove any json or pickle persistence files from your config directory.
Activate persistence in your config (and set version 1.5).
Connect your gateway
Start hass
Wait for "gateway started" log line
Connect the sensor
If no errors occur, open the newly generated persistence file and copy/paste the contents here. This might give us more clues.

martinhjelmare

I'm trying to replicate the problem, and there seem to be some issue with adding devices "on the fly" and not from the persistence file.

A workaround seems to be, as @asm reported:

Activate persistence
Present sensors and send a value per sensor
Restart home assistant

I'll try to figure out the cause and make a fix.

martinhjelmare

Ok, I've found the bug. I'll make the fix and pull request to home assistant. It should make the next release.

Until then the workaround above should be usable, at least.

Make sure you present the sensors and send at least one value per sensor, and the persistence file will be populated. Then after the restart of home assistant your sensors should show in the gui.

Sorry for the inconvenience, and thanks for helping me troubleshoot this! I really appreciate all feedback and also any criticism, which in the end will make the controller and plugin better.

asm

@martinhjelmare thank you !

Yesterday I finally got all the sensors in the UI using the workaround, so now it looks fine, but I'll update when the fix is available.

Thanks again!

Home Assistant v 0.11.1 with switch support

23

11.7k

11.2k

113.1k