Second test. I modified the Arduino sketch so node would start in ON state rather than previous state (which was off in last test). The logs below show HA bootup, node announcement, clicking on approximately 50% on dimmer slider, then 25%, then 75%, the clicking toggle to turn slider off, the two attempts to click slider to turn slider on.
HA log
Config directory: /srv/hass/cfg
17-01-03 19:06:01 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: service=turn_off, domain=homeassistant>
17-01-03 19:06:01 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: service=turn_on, domain=homeassistant>
17-01-03 19:06:01 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: service=toggle, domain=homeassistant>
17-01-03 19:06:01 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: service=reload_core_config, domain=homeassistant>
17-01-03 19:06:01 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: service=create, domain=persistent_notification>
17-01-03 19:06:01 INFO (MainThread) [homeassistant.bootstrap] Home Assistant core initialized
17-01-03 19:06:01 INFO (MainThread) [homeassistant.loader] Loaded mysensors from homeassistant.components.mysensors
17-01-03 19:06:01 INFO (MainThread) [homeassistant.loader] Loaded http from homeassistant.components.http
17-01-03 19:06:01 INFO (MainThread) [homeassistant.loader] Loaded logger from homeassistant.components.logger
17-01-03 19:06:01 INFO (MainThread) [homeassistant.loader] Loaded zeroconf from homeassistant.components.zeroconf
17-01-03 19:06:02 INFO (MainThread) [homeassistant.loader] Loaded api from homeassistant.components.api
17-01-03 19:06:02 INFO (MainThread) [homeassistant.loader] Loaded frontend from homeassistant.components.frontend
17-01-03 19:06:02 INFO (MainThread) [homeassistant.loader] Loaded websocket_api from homeassistant.components.websocket_api
17-01-03 19:06:02 INFO (MainThread) [homeassistant.bootstrap] Setting up logger
17-01-03 19:06:02 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=logger>
17-01-03 19:06:02 INFO (MainThread) [homeassistant.bootstrap] Setting up mysensors
17-01-03 19:06:02 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=mysensors>
17-01-03 19:06:02 INFO (MainThread) [homeassistant.bootstrap] Setting up http
17-01-03 19:06:02 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=http>
17-01-03 19:06:02 INFO (MainThread) [homeassistant.bootstrap] Setting up api
17-01-03 19:06:02 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=api>
17-01-03 19:06:02 INFO (MainThread) [homeassistant.bootstrap] Setting up zeroconf
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=zeroconf>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.bootstrap] Setting up websocket_api
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=websocket_api>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.bootstrap] Setting up frontend
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=frontend>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.loader] Loaded sensor from homeassistant.components.sensor
17-01-03 19:06:03 INFO (MainThread) [homeassistant.bootstrap] Setting up sensor
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=sensor>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event platform_discovered[L]: service=load_platform.sensor, platform=mysensors, discovered=>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.loader] Loaded switch from homeassistant.components.switch
17-01-03 19:06:03 INFO (MainThread) [homeassistant.loader] Loaded sensor.mysensors from homeassistant.components.sensor.mysensors
17-01-03 19:06:03 INFO (MainThread) [homeassistant.bootstrap] Setting up switch
17-01-03 19:06:03 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.mysensors
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: service=turn_off, domain=switch>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: service=turn_on, domain=switch>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: service=toggle, domain=switch>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=switch>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event platform_discovered[L]: service=load_platform.switch, platform=mysensors, discovered=>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.loader] Loaded light from homeassistant.components.light
17-01-03 19:06:03 INFO (MainThread) [homeassistant.loader] Loaded switch.mysensors from homeassistant.components.switch.mysensors
17-01-03 19:06:03 INFO (MainThread) [homeassistant.bootstrap] Setting up light
17-01-03 19:06:03 INFO (MainThread) [homeassistant.components.switch] Setting up switch.mysensors
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: service=mysensors_send_ir_code, domain=switch>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: service=turn_on, domain=light>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: service=turn_off, domain=light>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: service=toggle, domain=light>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=light>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event platform_discovered[L]: service=load_platform.light, platform=mysensors, discovered=>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.loader] Loaded binary_sensor from homeassistant.components.binary_sensor
17-01-03 19:06:03 INFO (MainThread) [homeassistant.loader] Loaded light.mysensors from homeassistant.components.light.mysensors
17-01-03 19:06:03 INFO (MainThread) [homeassistant.bootstrap] Setting up binary_sensor
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=binary_sensor>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event platform_discovered[L]: service=load_platform.binary_sensor, platform=mysensors, discovered=>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.loader] Loaded climate from homeassistant.components.climate
17-01-03 19:06:03 INFO (MainThread) [homeassistant.components.light] Setting up light.mysensors
17-01-03 19:06:03 INFO (MainThread) [homeassistant.loader] Loaded binary_sensor.mysensors from homeassistant.components.binary_sensor.mysensors
17-01-03 19:06:03 INFO (MainThread) [homeassistant.bootstrap] Setting up climate
17-01-03 19:06:03 INFO (MainThread) [homeassistant.components.binary_sensor] Setting up binary_sensor.mysensors
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: service=set_away_mode, domain=climate>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: service=set_aux_heat, domain=climate>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: service=set_temperature, domain=climate>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: service=set_humidity, domain=climate>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: service=set_fan_mode, domain=climate>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: service=set_operation_mode, domain=climate>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: service=set_swing_mode, domain=climate>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=climate>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event platform_discovered[L]: service=load_platform.climate, platform=mysensors, discovered=>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.loader] Loaded cover from homeassistant.components.cover
17-01-03 19:06:03 INFO (MainThread) [homeassistant.loader] Loaded climate.mysensors from homeassistant.components.climate.mysensors
17-01-03 19:06:03 INFO (MainThread) [homeassistant.bootstrap] Setting up cover
17-01-03 19:06:03 INFO (MainThread) [homeassistant.components.climate] Setting up climate.mysensors
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: service=open_cover_tilt, domain=cover>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: service=set_cover_position, domain=cover>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: service=close_cover, domain=cover>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: service=set_cover_tilt_position, domain=cover>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: service=stop_cover, domain=cover>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: service=close_cover_tilt, domain=cover>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: service=open_cover, domain=cover>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: service=stop_cover_tilt, domain=cover>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=cover>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event platform_discovered[L]: service=load_platform.cover, platform=mysensors, discovered=>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.loader] Loaded cover.mysensors from homeassistant.components.cover.mysensors
17-01-03 19:06:03 INFO (MainThread) [homeassistant.components.cover] Setting up cover.mysensors
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Starting Home Assistant core loop
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Starting Home Assistant
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: service=stop, domain=homeassistant>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: service=restart, domain=homeassistant>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event homeassistant_start[L]>
17-01-03 19:06:03 INFO (MainThread) [homeassistant.core] Timer:starting
17-01-03 19:06:03 INFO (Thread-11) [mysensors.mysensors] Trying to connect to ('127.0.0.1', 5003)
17-01-03 19:06:03 INFO (Thread-11) [mysensors.mysensors] Connected to ('127.0.0.1', 5003)
17-01-03 19:06:03 DEBUG (Thread-11) [mysensors.mysensors] Received 0;255;3;0;14;Gateway startup complete.
0;255;0;0;18;2.2.0-beta
17-01-03 19:06:03 DEBUG (Thread-11) [homeassistant.components.mysensors] Update sensor_update: node 0
17-01-03 19:06:03 INFO (Thread-11) [homeassistant.components.mysensors] No sketch_name: node 0
17-01-03 19:06:03 INFO (Thread-11) [homeassistant.components.mysensors] No sketch_name: node 0
17-01-03 19:06:03 INFO (Thread-11) [homeassistant.components.mysensors] No sketch_name: node 0
17-01-03 19:06:03 INFO (Thread-11) [homeassistant.components.mysensors] No sketch_name: node 0
17-01-03 19:06:03 INFO (Thread-11) [homeassistant.components.mysensors] No sketch_name: node 0
17-01-03 19:06:03 INFO (Thread-11) [homeassistant.components.mysensors] No sketch_name: node 0
17-01-03 19:06:11 INFO (MainThread) [homeassistant.components.http] Serving /api/websocket to 192.168.9.91 (auth: True)
17-01-03 19:06:11 INFO (MainThread) [homeassistant.components.http] Serving /api/bootstrap to 192.168.9.91 (auth: True)
17-01-03 19:06:18 DEBUG (Thread-11) [mysensors.mysensors] Received 123;255;0;0;17;2.2.0-beta
123;255;3;0;6;0
17-01-03 19:06:18 DEBUG (Thread-11) [homeassistant.components.mysensors] Update sensor_update: node 123
17-01-03 19:06:18 INFO (Thread-11) [homeassistant.components.mysensors] No sketch_name: node 123
17-01-03 19:06:18 INFO (Thread-11) [homeassistant.components.mysensors] No sketch_name: node 123
17-01-03 19:06:18 INFO (Thread-11) [homeassistant.components.mysensors] No sketch_name: node 123
17-01-03 19:06:18 INFO (Thread-11) [homeassistant.components.mysensors] No sketch_name: node 123
17-01-03 19:06:18 INFO (Thread-11) [homeassistant.components.mysensors] No sketch_name: node 123
17-01-03 19:06:18 INFO (Thread-11) [homeassistant.components.mysensors] No sketch_name: node 123
17-01-03 19:06:18 DEBUG (Thread-11) [mysensors.mysensors] Sending 123;255;3;0;6;I
17-01-03 19:06:18 DEBUG (Thread-11) [mysensors.mysensors] Received 123;255;3;0;11;Dimable Light
123;255;3;0;12;1.0
123;1;0;0;4;
17-01-03 19:06:18 DEBUG (Thread-11) [homeassistant.components.mysensors] Update sensor_update: node 123
17-01-03 19:06:18 DEBUG (Thread-11) [homeassistant.components.mysensors] Update sensor_update: node 123
17-01-03 19:06:18 DEBUG (Thread-11) [homeassistant.components.mysensors] Update sensor_update: node 123
17-01-03 19:06:18 DEBUG (Thread-11) [mysensors.mysensors] Received 123;1;1;0;3;100
17-01-03 19:06:18 DEBUG (Thread-11) [homeassistant.components.mysensors] Update sensor_update: node 123
17-01-03 19:06:18 INFO (Thread-11) [homeassistant.components.mysensors] Adding new devices: <Entity Dimable Light 123 1: off>
17-01-03 19:06:18 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: new_state=<state light.dimable_light_123_1=unavailable; friendly_name=Dimable Light 123 1 @ 2017-01-03T19:06:18.644530-05:00>, old_state=None, entity_id=light.dimable_light_123_1>
17-01-03 19:06:18 INFO (MainThread) [homeassistant.loader] Loaded group from homeassistant.components.group
17-01-03 19:06:18 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: new_state=<state group.all_lights=unknown; friendly_name=all lights, order=0, auto=True, entity_id=('light.dimable_light_123_1',), hidden=True @ 2017-01-03T19:06:18.652482-05:00>, old_state=None, entity_id=group.all_lights>
17-01-03 19:06:18 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: new_state=<state light.dimable_light_123_1=on; friendly_name=Dimable Light 123 1, device=127.0.0.1, battery_level=0, V_PERCENTAGE=100, node_id=123, child_id=1, brightness=255, supported_features=145, description= @ 2017-01-03T19:06:18.660841-05:00>, old_state=<state light.dimable_light_123_1=unavailable; friendly_name=Dimable Light 123 1 @ 2017-01-03T19:06:18.644530-05:00>, entity_id=light.dimable_light_123_1>
17-01-03 19:06:18 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: new_state=<state group.all_lights=on; friendly_name=all lights, order=0, auto=True, entity_id=('light.dimable_light_123_1',), hidden=True @ 2017-01-03T19:06:18.668010-05:00>, old_state=<state group.all_lights=unknown; friendly_name=all lights, order=0, auto=True, entity_id=('light.dimable_light_123_1',), hidden=True @ 2017-01-03T19:06:18.652482-05:00>, entity_id=group.all_lights>
17-01-03 19:06:42 INFO (MainThread) [homeassistant.components.http] Serving /api/services/light/turn_on to 192.168.9.91 (auth: True)
17-01-03 19:06:42 INFO (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: service=turn_on, domain=light, service_call_id=1976609200-1, service_data=entity_id=light.dimable_light_123_1, brightness=120>
17-01-03 19:06:42 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_executed[L]: service_call_id=1976609200-1>
17-01-03 19:06:42 DEBUG (Thread-11) [mysensors.mysensors] Sending 123;1;1;0;3;47
17-01-03 19:06:42 DEBUG (Thread-11) [mysensors.mysensors] Received 123;1;1;0;3;47
17-01-03 19:06:42 DEBUG (Thread-11) [homeassistant.components.mysensors] Update sensor_update: node 123
17-01-03 19:06:42 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: new_state=<state light.dimable_light_123_1=on; friendly_name=Dimable Light 123 1, device=127.0.0.1, battery_level=0, V_PERCENTAGE=47, node_id=123, child_id=1, brightness=120, supported_features=145, description= @ 2017-01-03T19:06:18.660841-05:00>, old_state=<state light.dimable_light_123_1=on; friendly_name=Dimable Light 123 1, device=127.0.0.1, battery_level=0, V_PERCENTAGE=100, node_id=123, child_id=1, brightness=255, supported_features=145, description= @ 2017-01-03T19:06:18.660841-05:00>, entity_id=light.dimable_light_123_1>
17-01-03 19:06:51 INFO (MainThread) [homeassistant.components.http] Serving /api/services/light/turn_on to 192.168.9.91 (auth: True)
17-01-03 19:06:51 INFO (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: service=turn_on, domain=light, service_call_id=1976609200-2, service_data=entity_id=light.dimable_light_123_1, brightness=56>
17-01-03 19:06:51 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_executed[L]: service_call_id=1976609200-2>
17-01-03 19:06:51 DEBUG (Thread-11) [mysensors.mysensors] Sending 123;1;1;0;3;22
17-01-03 19:06:52 DEBUG (Thread-11) [mysensors.mysensors] Received 123;1;1;0;3;22
17-01-03 19:06:52 DEBUG (Thread-11) [homeassistant.components.mysensors] Update sensor_update: node 123
17-01-03 19:06:52 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: new_state=<state light.dimable_light_123_1=on; friendly_name=Dimable Light 123 1, device=127.0.0.1, battery_level=0, V_PERCENTAGE=22, node_id=123, child_id=1, brightness=56, supported_features=145, description= @ 2017-01-03T19:06:18.660841-05:00>, old_state=<state light.dimable_light_123_1=on; friendly_name=Dimable Light 123 1, device=127.0.0.1, battery_level=0, V_PERCENTAGE=47, node_id=123, child_id=1, brightness=120, supported_features=145, description= @ 2017-01-03T19:06:18.660841-05:00>, entity_id=light.dimable_light_123_1>
17-01-03 19:06:57 INFO (MainThread) [homeassistant.components.http] Serving /api/services/light/turn_on to 192.168.9.91 (auth: True)
17-01-03 19:06:57 INFO (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: service=turn_on, domain=light, service_call_id=1976609200-3, service_data=entity_id=light.dimable_light_123_1, brightness=193>
17-01-03 19:06:57 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_executed[L]: service_call_id=1976609200-3>
17-01-03 19:06:57 DEBUG (Thread-11) [mysensors.mysensors] Sending 123;1;1;0;3;76
17-01-03 19:06:57 DEBUG (Thread-11) [mysensors.mysensors] Received 123;1;1;0;3;76
17-01-03 19:06:57 DEBUG (Thread-11) [homeassistant.components.mysensors] Update sensor_update: node 123
17-01-03 19:06:57 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: new_state=<state light.dimable_light_123_1=on; friendly_name=Dimable Light 123 1, device=127.0.0.1, battery_level=0, V_PERCENTAGE=76, node_id=123, child_id=1, brightness=194, supported_features=145, description= @ 2017-01-03T19:06:18.660841-05:00>, old_state=<state light.dimable_light_123_1=on; friendly_name=Dimable Light 123 1, device=127.0.0.1, battery_level=0, V_PERCENTAGE=22, node_id=123, child_id=1, brightness=56, supported_features=145, description= @ 2017-01-03T19:06:18.660841-05:00>, entity_id=light.dimable_light_123_1>
17-01-03 19:07:01 INFO (MainThread) [homeassistant.components.http] Serving /api/services/homeassistant/turn_off to 192.168.9.91 (auth: True)
17-01-03 19:07:01 INFO (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: service=turn_off, domain=homeassistant, service_call_id=1976609200-4, service_data=entity_id=light.dimable_light_123_1>
17-01-03 19:07:01 INFO (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: service=turn_off, domain=light, service_call_id=1976609200-5, service_data=entity_id=['light.dimable_light_123_1']>
17-01-03 19:07:01 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_executed[L]: service_call_id=1976609200-5>
17-01-03 19:07:01 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_executed[L]: service_call_id=1976609200-4>
17-01-03 19:07:01 DEBUG (Thread-11) [mysensors.mysensors] Sending 123;1;1;0;3;0
17-01-03 19:07:01 DEBUG (Thread-11) [mysensors.mysensors] Received 123;1;1;0;3;0
17-01-03 19:07:01 DEBUG (Thread-11) [homeassistant.components.mysensors] Update sensor_update: node 123
17-01-03 19:07:01 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: new_state=<state light.dimable_light_123_1=off; friendly_name=Dimable Light 123 1, child_id=1, description=, battery_level=0, V_PERCENTAGE=0, supported_features=145, node_id=123, device=127.0.0.1 @ 2017-01-03T19:07:01.105143-05:00>, old_state=<state light.dimable_light_123_1=on; friendly_name=Dimable Light 123 1, device=127.0.0.1, battery_level=0, V_PERCENTAGE=76, node_id=123, child_id=1, brightness=194, supported_features=145, description= @ 2017-01-03T19:06:18.660841-05:00>, entity_id=light.dimable_light_123_1>
17-01-03 19:07:01 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: new_state=<state group.all_lights=off; friendly_name=all lights, order=0, auto=True, entity_id=('light.dimable_light_123_1',), hidden=True @ 2017-01-03T19:07:01.109500-05:00>, old_state=<state group.all_lights=on; friendly_name=all lights, order=0, auto=True, entity_id=('light.dimable_light_123_1',), hidden=True @ 2017-01-03T19:06:18.668010-05:00>, entity_id=group.all_lights>
17-01-03 19:07:07 INFO (MainThread) [homeassistant.components.http] Serving /api/services/homeassistant/turn_on to 192.168.9.91 (auth: True)
17-01-03 19:07:07 INFO (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: service=turn_on, domain=homeassistant, service_call_id=1976609200-6, service_data=entity_id=light.dimable_light_123_1>
17-01-03 19:07:07 INFO (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: service=turn_on, domain=light, service_call_id=1976609200-7, service_data=entity_id=['light.dimable_light_123_1']>
17-01-03 19:07:07 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_executed[L]: service_call_id=1976609200-7>
17-01-03 19:07:07 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_executed[L]: service_call_id=1976609200-6>
17-01-03 19:07:13 INFO (MainThread) [homeassistant.components.http] Serving /api/services/homeassistant/turn_on to 192.168.9.91 (auth: True)
17-01-03 19:07:13 INFO (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: service=turn_on, domain=homeassistant, service_call_id=1976609200-8, service_data=entity_id=light.dimable_light_123_1>
17-01-03 19:07:13 INFO (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: service=turn_on, domain=light, service_call_id=1976609200-9, service_data=entity_id=['light.dimable_light_123_1']>
17-01-03 19:07:13 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_executed[L]: service_call_id=1976609200-9>
17-01-03 19:07:13 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_executed[L]: service_call_id=1976609200-8>
^C17-01-03 19:07:42 INFO (MainThread) [homeassistant.core] Bus:Handling <Event homeassistant_stop[L]>
17-01-03 19:07:42 INFO (Thread-7) [mysensors.mysensors] Stopping thread
17-01-03 19:07:42 INFO (Thread-11) [mysensors.mysensors] Closing socket at ('127.0.0.1', 5003).
17-01-03 19:07:42 INFO (Thread-11) [mysensors.mysensors] Socket closed at ('127.0.0.1', 5003).
^CException ignored in: <module 'threading' from '/usr/lib/python3.4/threading.py'>
Traceback (most recent call last):
File "/usr/lib/python3.4/threading.py", line 1294, in _shutdown
t.join()
File "/usr/lib/python3.4/threading.py", line 1060, in join
self._wait_for_tstate_lock()
File "/usr/lib/python3.4/threading.py", line 1076, in _wait_for_tstate_lock
elif lock.acquire(block, timeout):
KeyboardInterrupt
hass@pi:/srv/hass/cfg $ exit
Script done on Tue 03 Jan 2017 07:07:47 PM EST
Node console
0 MCO:BGN:INIT NODE,CP=RNNNA--,VER=2.2.0-beta
4 TSM:INIT
4 TSF:WUR:MS=0
11 TSM:INIT:TSP OK
13 TSM:INIT:STATID=123
15 TSF:SID:OK,ID=123
17 TSM:FPAR
53 TSF:MSG:SEND,123-123-255-255,s=255,c=3,t=7,pt=0,l=0,sg=0,ft=0,st=OK:
273 TSF:MSG:READ,0-0-123,s=255,c=3,t=8,pt=1,l=1,sg=0:0
278 TSF:MSG:FPAR OK,ID=0,D=1
2061 TSM:FPAR:OK
2062 TSM:ID
2063 TSM:ID:OK
2065 TSM:UPL
2068 TSF:MSG:SEND,123-123-0-0,s=255,c=3,t=24,pt=1,l=1,sg=0,ft=0,st=OK:1
2074 TSF:MSG:READ,0-0-123,s=255,c=3,t=25,pt=1,l=1,sg=0:1
2079 TSF:MSG:PONG RECV,HP=1
2082 TSM:UPL:OK
2083 TSM:READY:ID=123,PAR=0,DIS=1
2088 TSF:MSG:SEND,123-123-0-0,s=255,c=3,t=15,pt=6,l=2,sg=0,ft=0,st=OK:0100
2096 TSF:MSG:READ,0-0-123,s=255,c=3,t=15,pt=6,l=2,sg=0:0100
2104 TSF:MSG:SEND,123-123-0-0,s=255,c=0,t=17,pt=0,l=10,sg=0,ft=0,st=OK:2.2.0-beta
2113 TSF:MSG:SEND,123-123-0-0,s=255,c=3,t=6,pt=1,l=1,sg=0,ft=0,st=OK:0
2160 TSF:MSG:READ,0-0-123,s=255,c=3,t=6,pt=0,l=1,sg=0:I
2167 TSF:MSG:SEND,123-123-0-0,s=255,c=3,t=11,pt=0,l=13,sg=0,ft=0,st=OK:Dimable Light
2178 TSF:MSG:SEND,123-123-0-0,s=255,c=3,t=12,pt=0,l=3,sg=0,ft=0,st=OK:1.0
2186 TSF:MSG:SEND,123-123-0-0,s=1,c=0,t=4,pt=0,l=0,sg=0,ft=0,st=OK:
2192 MCO:REG:REQ
2195 TSF:MSG:SEND,123-123-0-0,s=255,c=3,t=26,pt=1,l=1,sg=0,ft=0,st=OK:2
2205 TSF:MSG:READ,0-0-123,s=255,c=3,t=27,pt=1,l=1,sg=0:1
2210 MCO:PIM:NODE REG=1
2213 MCO:BGN:STP
Light state: ON, Level: 100
2220 TSF:MSG:SEND,123-123-0-0,s=1,c=1,t=3,pt=2,l=2,sg=0,ft=0,st=OK:100
Node ready to receive messages...
2226 MCO:BGN:INIT OK,TSP=1
25995 TSF:MSG:READ,0-0-123,s=1,c=1,t=3,pt=0,l=2,sg=0:47
V_DIMMER command received...
Light state: ON, Level: 47
26003 TSF:MSG:SEND,123-123-0-0,s=1,c=1,t=3,pt=2,l=2,sg=0,ft=0,st=OK:47
35651 TSF:MSG:READ,0-0-123,s=1,c=1,t=3,pt=0,l=2,sg=0:22
V_DIMMER command received...
Light state: ON, Level: 22
35660 TSF:MSG:SEND,123-123-0-0,s=1,c=1,t=3,pt=2,l=2,sg=0,ft=0,st=OK:22
40934 TSF:MSG:READ,0-0-123,s=1,c=1,t=3,pt=0,l=2,sg=0:76
V_DIMMER command received...
Light state: ON, Level: 76
40946 TSF:MSG:SEND,123-123-0-0,s=1,c=1,t=3,pt=2,l=2,sg=0,ft=0,st=OK:76
44735 TSF:MSG:READ,0-0-123,s=1,c=1,t=3,pt=0,l=1,sg=0:0
V_DIMMER command received...
Light state: OFF
44744 TSF:MSG:SEND,123-123-0-0,s=1,c=1,t=3,pt=2,l=2,sg=0,ft=0,st=OK:0