2018-07-21 21:29:44 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=logger, service=set_default_level>
2018-07-21 21:29:44 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=logger, service=set_level>
2018-07-21 21:29:44 INFO (MainThread) [homeassistant.setup] Setup of domain logger took 0.0 seconds.
2018-07-21 21:29:44 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=logger>
2018-07-21 21:29:45 INFO (MainThread) [homeassistant.loader] Loaded frontend from homeassistant.components.frontend
2018-07-21 21:29:45 INFO (MainThread) [homeassistant.loader] Loaded api from homeassistant.components.api
2018-07-21 21:29:45 INFO (MainThread) [homeassistant.loader] Loaded websocket_api from homeassistant.components.websocket_api
2018-07-21 21:29:45 INFO (MainThread) [homeassistant.loader] Loaded system_log from homeassistant.components.system_log
2018-07-21 21:29:45 INFO (MainThread) [homeassistant.loader] Loaded onboarding from homeassistant.components.onboarding
2018-07-21 21:29:45 INFO (MainThread) [homeassistant.loader] Loaded introduction from homeassistant.components.introduction
2018-07-21 21:29:45 INFO (MainThread) [homeassistant.setup] Setting up introduction
2018-07-21 21:29:45 INFO (MainThread) [homeassistant.components.introduction] 

    ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~

        Hello, and welcome to Home Assistant!

        We'll hope that we can make all your dreams come true.

        Here are some resources to get started:

         - Configuring Home Assistant:
           https://home-assistant.io/getting-started/configuration/

         - Available components:
           https://home-assistant.io/components/

         - Troubleshooting your configuration:
           https://home-assistant.io/getting-started/troubleshooting-configuration/

         - Getting help:
           https://home-assistant.io/help/

        This message is generated by the introduction component. You can
        disable it in configuration.yaml.

    ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
    
2018-07-21 21:29:45 INFO (MainThread) [homeassistant.loader] Loaded persistent_notification from homeassistant.components.persistent_notification
2018-07-21 21:29:45 INFO (MainThread) [homeassistant.setup] Setup of domain introduction took 0.0 seconds.
2018-07-21 21:29:45 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=introduction>
2018-07-21 21:29:45 INFO (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=persistent_notification, service=create, service_data=title=Welcome Home!, message=
Here are some resources to get started:

 - [Configuring Home Assistant](https://home-assistant.io/getting-started/configuration/)
 - [Available components](https://home-assistant.io/components/)
 - [Troubleshooting your configuration](https://home-assistant.io/docs/configuration/troubleshooting/)
 - [Getting help](https://home-assistant.io/help/)

To not see this card popup in the future, edit your config in
`configuration.yaml` and disable the `introduction` component.
, service_call_id=548372961432-1>
2018-07-21 21:29:45 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=persistent_notification.notification, old_state=None, new_state=<state persistent_notification.notification=notifying; title=Welcome Home!, message=Here are some resources to get started:

 - [Configuring Home Assistant](https://home-assistant.io/getting-started/configuration/)
 - [Available components](https://home-assistant.io/components/)
 - [Troubleshooting your configuration](https://home-assistant.io/docs/configuration/troubleshooting/)
 - [Getting help](https://home-assistant.io/help/)

To not see this card popup in the future, edit your config in
`configuration.yaml` and disable the `introduction` component. @ 2018-07-21T21:29:45.613855+00:00>>
2018-07-21 21:29:45 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_executed[L]: service_call_id=548372961432-1>
2018-07-21 21:29:48 INFO (MainThread) [homeassistant.setup] Setting up recorder
2018-07-21 21:29:48 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=recorder, service=purge>
2018-07-21 21:29:52 DEBUG (Recorder) [homeassistant.components.recorder] Connected to recorder database
2018-07-21 21:29:52 INFO (MainThread) [homeassistant.setup] Setup of domain recorder took 4.0 seconds.
2018-07-21 21:29:52 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=recorder>
2018-07-21 21:29:53 INFO (MainThread) [homeassistant.setup] Setting up http
2018-07-21 21:29:53 WARNING (MainThread) [homeassistant.components.http] You have been advised to set http.api_password.
2018-07-21 21:29:53 INFO (MainThread) [homeassistant.setup] Setup of domain http took 0.1 seconds.
2018-07-21 21:29:53 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=http>
2018-07-21 21:29:53 INFO (MainThread) [homeassistant.setup] Setting up history
2018-07-21 21:29:53 INFO (MainThread) [homeassistant.setup] Setup of domain history took 0.0 seconds.
2018-07-21 21:29:53 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=history>
2018-07-21 21:29:53 INFO (MainThread) [homeassistant.setup] Setting up websocket_api
2018-07-21 21:29:53 INFO (MainThread) [homeassistant.setup] Setup of domain websocket_api took 0.0 seconds.
2018-07-21 21:29:53 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=websocket_api>
2018-07-21 21:29:53 INFO (MainThread) [homeassistant.setup] Setting up system_log
2018-07-21 21:29:53 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=system_log, service=clear>
2018-07-21 21:29:53 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=system_log, service=write>
2018-07-21 21:29:53 INFO (MainThread) [homeassistant.setup] Setup of domain system_log took 0.0 seconds.
2018-07-21 21:29:53 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=system_log>
2018-07-21 21:29:53 INFO (MainThread) [homeassistant.setup] Setting up onboarding
2018-07-21 21:29:53 INFO (MainThread) [homeassistant.setup] Setting up api
2018-07-21 21:29:53 DEBUG (SyncWorker_0) [homeassistant.util.json] JSON file not found: /config/.storage/onboarding
2018-07-21 21:29:53 INFO (MainThread) [homeassistant.setup] Setup of domain api took 0.0 seconds.
2018-07-21 21:29:53 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=api>
2018-07-21 21:29:53 DEBUG (SyncWorker_4) [homeassistant.util.json] JSON file not found: /config/.storage/auth
2018-07-21 21:29:53 INFO (MainThread) [homeassistant.setup] Setup of domain onboarding took 0.1 seconds.
2018-07-21 21:29:53 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=onboarding>
2018-07-21 21:29:55 INFO (MainThread) [homeassistant.setup] Setting up frontend
2018-07-21 21:30:00 INFO (MainThread) [homeassistant.setup] Setup of domain frontend took 4.5 seconds.
2018-07-21 21:30:00 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=frontend>
2018-07-21 21:30:00 INFO (MainThread) [homeassistant.loader] Loaded logbook from homeassistant.components.logbook
2018-07-21 21:30:00 INFO (MainThread) [homeassistant.loader] Loaded map from homeassistant.components.map
2018-07-21 21:30:00 INFO (MainThread) [homeassistant.setup] Setting up map
2018-07-21 21:30:00 INFO (MainThread) [homeassistant.setup] Setup of domain map took 0.0 seconds.
2018-07-21 21:30:00 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=map>
2018-07-21 21:30:01 INFO (MainThread) [homeassistant.loader] Loaded cloud from homeassistant.components.cloud
2018-07-21 21:30:01 INFO (MainThread) [homeassistant.loader] Loaded sensor from homeassistant.components.sensor
2018-07-21 21:30:01 INFO (MainThread) [homeassistant.loader] Loaded sensor.yr from homeassistant.components.sensor.yr
2018-07-21 21:30:01 INFO (MainThread) [homeassistant.setup] Setting up sensor
2018-07-21 21:30:01 INFO (MainThread) [homeassistant.loader] Loaded group from homeassistant.components.group
2018-07-21 21:30:01 INFO (MainThread) [homeassistant.setup] Setting up group
2018-07-21 21:30:01 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=group, service=reload>
2018-07-21 21:30:01 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=group, service=set>
2018-07-21 21:30:01 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=group, service=remove>
2018-07-21 21:30:01 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=group, service=set_visibility>
2018-07-21 21:30:01 INFO (MainThread) [homeassistant.setup] Setup of domain group took 0.0 seconds.
2018-07-21 21:30:01 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=group>
2018-07-21 21:30:01 INFO (MainThread) [homeassistant.loader] Loaded conversation from homeassistant.components.conversation
2018-07-21 21:30:01 INFO (MainThread) [homeassistant.loader] Loaded automation from homeassistant.components.automation
2018-07-21 21:30:01 INFO (MainThread) [homeassistant.loader] Loaded script from homeassistant.components.script
2018-07-21 21:30:01 INFO (MainThread) [homeassistant.loader] Loaded tts from homeassistant.components.tts
2018-07-21 21:30:01 INFO (MainThread) [homeassistant.loader] Loaded tts.google from homeassistant.components.tts.google
2018-07-21 21:30:01 INFO (MainThread) [custom_components.pyvisonic] Initialising Protocol
2018-07-21 21:30:01 INFO (MainThread) [homeassistant.loader] Loaded visonic from custom_components.visonic
2018-07-21 21:30:01 WARNING (MainThread) [homeassistant.loader] You are using a custom component for visonic which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you do experience issues with Home Assistant.
2018-07-21 21:30:01 INFO (MainThread) [homeassistant.loader] Loaded cast from homeassistant.components.cast
2018-07-21 21:30:01 INFO (MainThread) [homeassistant.loader] Loaded updater from homeassistant.components.updater
2018-07-21 21:30:01 INFO (MainThread) [homeassistant.loader] Loaded discovery from homeassistant.components.discovery
2018-07-21 21:30:01 INFO (MainThread) [homeassistant.loader] Loaded sun from homeassistant.components.sun
2018-07-21 21:30:01 INFO (MainThread) [homeassistant.setup] Setting up sun
2018-07-21 21:30:03 INFO (MainThread) [homeassistant.setup] Setup of domain sun took 2.3 seconds.
2018-07-21 21:30:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=sun>
2018-07-21 21:30:03 INFO (MainThread) [homeassistant.loader] Loaded config from homeassistant.components.config
2018-07-21 21:30:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sun.sun, old_state=None, new_state=<state sun.sun=below_horizon; next_dawn=2018-07-22T05:40:51+00:00, next_dusk=2018-07-22T18:32:03+00:00, next_midnight=2018-07-22T00:06:28+00:00, next_noon=2018-07-22T12:06:27+00:00, next_rising=2018-07-22T06:02:54+00:00, next_setting=2018-07-22T18:10:00+00:00, elevation=-46.75, azimuth=300.21, friendly_name=Sun @ 2018-07-21T21:30:03.875818+00:00>>
2018-07-21 21:30:03 INFO (MainThread) [homeassistant.setup] Setting up logbook
2018-07-21 21:30:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=logbook, service=log>
2018-07-21 21:30:03 INFO (MainThread) [homeassistant.setup] Setup of domain logbook took 0.0 seconds.
2018-07-21 21:30:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=logbook>
2018-07-21 21:30:03 INFO (MainThread) [homeassistant.setup] Setting up conversation
2018-07-21 21:30:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=conversation, service=process>
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.setup] Setup of domain conversation took 0.1 seconds.
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=conversation>
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.setup] Setting up automation
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=automation, service=trigger>
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=automation, service=reload>
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=automation, service=toggle>
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=automation, service=turn_on>
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=automation, service=turn_off>
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.setup] Setup of domain automation took 0.0 seconds.
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=automation>
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.setup] Setting up script
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=script, service=reload>
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=script, service=turn_on>
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=script, service=turn_off>
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=script, service=toggle>
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.setup] Setup of domain script took 0.0 seconds.
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=script>
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.setup] Setting up config
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.loader] Loaded config.group from homeassistant.components.config.group
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=config.group>
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.loader] Loaded config.core from homeassistant.components.config.core
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=config.core>
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.loader] Loaded config.hassbian from homeassistant.components.config.hassbian
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.loader] Loaded config.automation from homeassistant.components.config.automation
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=config.automation>
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.loader] Loaded config.customize from homeassistant.components.config.customize
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=config.customize>
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.loader] Loaded config.script from homeassistant.components.config.script
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=config.script>
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.loader] Loaded config.entity_registry from homeassistant.components.config.entity_registry
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.loader] Loaded config.config_entries from homeassistant.components.config.config_entries
2018-07-21 21:30:04 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=config.entity_registry>
2018-07-21 21:30:10 INFO (MainThread) [homeassistant.setup] Setting up visonic
2018-07-21 21:30:10 WARNING (SyncWorker_3) [custom_components.pyvisonic] Setting key MotionOffDelay to value 120
2018-07-21 21:30:10 WARNING (SyncWorker_3) [custom_components.pyvisonic] Setting key PluginLanguage to value EN
2018-07-21 21:30:10 WARNING (SyncWorker_3) [custom_components.pyvisonic] Setting key PluginDebug to value False
2018-07-21 21:30:10 WARNING (SyncWorker_3) [custom_components.pyvisonic] Setting key ForceStandard to value False
2018-07-21 21:30:10 WARNING (SyncWorker_3) [custom_components.pyvisonic] Setting key AutoSyncTime to value True
2018-07-21 21:30:10 WARNING (SyncWorker_3) [custom_components.pyvisonic] Setting key EnableRemoteArm to value True
2018-07-21 21:30:10 WARNING (SyncWorker_3) [custom_components.pyvisonic] Setting key EnableRemoteDisArm to value True
2018-07-21 21:30:10 WARNING (SyncWorker_3) [custom_components.pyvisonic] Setting key EnableSensorBypass to value None
2018-07-21 21:30:10 WARNING (SyncWorker_3) [custom_components.pyvisonic] Setting key OverrideCode to value 5566
2018-07-21 21:30:10 WARNING (SyncWorker_3) [custom_components.pyvisonic] Setting key ResetCounter to value 0
2018-07-21 21:30:10 INFO (SyncWorker_3) [custom_components.visonic] Visonic Connection Device Type is OrderedDict([('type', 'usb'), ('path', '/dev/ttyUSB0'), ('baud', '9600')])
2018-07-21 21:30:11 INFO (MainThread) [custom_components.pyvisonic] [Connection] Connected. Please wait up to 5 minutes for Sensors and X10 Devices to Appear
2018-07-21 21:30:11 INFO (MainThread) [homeassistant.setup] Setup of domain visonic took 0.7 seconds.
2018-07-21 21:30:11 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=visonic>
2018-07-21 21:30:11 INFO (MainThread) [custom_components.pyvisonic] [Startup] Trying to initialise panel
2018-07-21 21:30:11 INFO (MainThread) [custom_components.pyvisonic] [QueueMessage] Exit
2018-07-21 21:30:11 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Exit)    raw data 0d 0f f0 0a 
2018-07-21 21:30:12 INFO (MainThread) [custom_components.pyvisonic] [QueueMessage] Stop
2018-07-21 21:30:12 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Stop)    raw data 0d 0b f4 0a 
2018-07-21 21:30:13 INFO (MainThread) [custom_components.pyvisonic] [QueueMessage] Initializing PowerMax/Master PowerLink Connection
2018-07-21 21:30:13 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Initializing PowerMax/Master PowerLink Connection)    raw data 0d ab 0a 00 01 00 00 00 00 00 00 00 43 06 0a 
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.loader] Loaded switch from homeassistant.components.switch
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.loader] Loaded alarm_control_panel from custom_components.alarm_control_panel
2018-07-21 21:30:14 WARNING (MainThread) [homeassistant.loader] You are using a custom component for alarm_control_panel which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you do experience issues with Home Assistant.
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.setup] Setting up alarm_control_panel
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=alarm_control_panel, service=alarm_disarm>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=alarm_control_panel, service=alarm_arm_home>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=alarm_control_panel, service=alarm_arm_away>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=alarm_control_panel, service=alarm_arm_night>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=alarm_control_panel, service=alarm_arm_custom_bypass>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=alarm_control_panel, service=alarm_trigger>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.setup] Setup of domain alarm_control_panel took 0.0 seconds.
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=alarm_control_panel>
2018-07-21 21:30:14 WARNING (MainThread) [homeassistant.setup] Setup of config is taking over 10 seconds.
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event platform_discovered[L]: service=load_platform.alarm_control_panel, platform=visonic>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.setup] Setting up cast
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.setup] Setup of domain cast took 0.0 seconds.
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=cast>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.setup] Setting up switch
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=switch, service=turn_off>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=switch, service=turn_on>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=switch, service=toggle>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.setup] Setup of domain switch took 0.0 seconds.
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=switch>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.loader] Loaded alarm_control_panel.visonic from custom_components.alarm_control_panel.visonic
2018-07-21 21:30:14 WARNING (MainThread) [homeassistant.loader] You are using a custom component for alarm_control_panel.visonic which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you do experience issues with Home Assistant.
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.loader] Loaded media_player from homeassistant.components.media_player
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event platform_discovered[L]: service=load_platform.switch, platform=visonic>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.loader] Loaded switch.visonic from custom_components.switch.visonic
2018-07-21 21:30:14 WARNING (MainThread) [homeassistant.loader] You are using a custom component for switch.visonic which has not been tested by Home Assistant. This component might cause stability problems, be sure to disable it if you do experience issues with Home Assistant.
2018-07-21 21:30:14 INFO (MainThread) [custom_components.alarm_control_panel] Setting up alarm_control_panel.visonic
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.setup] Setting up media_player
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=media_player, service=turn_on>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=media_player, service=turn_off>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=media_player, service=toggle>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=media_player, service=volume_up>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=media_player, service=volume_down>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=media_player, service=media_play_pause>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=media_player, service=media_play>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=media_player, service=media_pause>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=media_player, service=media_stop>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=media_player, service=media_next_track>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=media_player, service=media_previous_track>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=media_player, service=clear_playlist>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=media_player, service=volume_set>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=media_player, service=volume_mute>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=media_player, service=media_seek>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=media_player, service=select_source>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=media_player, service=select_sound_mode>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=media_player, service=play_media>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=media_player, service=shuffle_set>
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.setup] Setup of domain media_player took 0.4 seconds.
2018-07-21 21:30:14 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=media_player>
2018-07-21 21:30:15 INFO (MainThread) [homeassistant.loader] Loaded media_player.cast from homeassistant.components.media_player.cast
2018-07-21 21:30:15 INFO (MainThread) [homeassistant.components.switch] Setting up switch.visonic
2018-07-21 21:30:15 INFO (MainThread) [homeassistant.components.media_player] Setting up media_player.cast
2018-07-21 21:30:16 DEBUG (SyncWorker_2) [homeassistant.components.media_player.cast] Starting internal pychromecast discovery.
2018-07-21 21:30:16 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=alarm_control_panel.visonic_alarm, old_state=None, new_state=<state alarm_control_panel.visonic_alarm=unknown; code_format=None, changed_by=None, friendly_name=Visonic Alarm @ 2018-07-21T21:30:16.336204+00:00>>
2018-07-21 21:30:16 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=switch.visonic_alarm_panel, old_state=None, new_state=<state switch.visonic_alarm_panel=standby; PluginVersion=0.0.1, CommExceptionCount=0, Mode=Starting, PanelLastEvent=None, PanelAlarmStatus=None, PanelTroubleStatus=None, PanelSirenActive=False, PanelStatus=Unknown, PanelStatusCode=-1, PanelReady=False, PanelAlertInMemory=False, PanelTrouble=False, PanelBypass=False, PanelStatusChanged=False, PanelAlarmEvent=False, Model=, ModelType=Unknown, PanelArmed=False, PowerMaster=False, PanelSoftware=Unknown, PanelName=Unknown, PanelSerial=Unknown, DoorZones=Unknown, MotionZones=Unknown, SmokeZones=Unknown, OtherZones=Unknown, Devices=Unknown, PhoneNumbers=, EntryTime1=0, EntryTime2=0, ExitTime=0, BellTime=0, SilentPanic=False, QuickArm=False, BypassOff=False, friendly_name=Visonic Alarm Panel @ 2018-07-21T21:30:16.370850+00:00>>
2018-07-21 21:30:16 INFO (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=group, service=set, service_data=object_id=all_switches, name=all switches, entities=['switch.visonic_alarm_panel'], visible=False, service_call_id=548372961432-2>
2018-07-21 21:30:16 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=group.all_switches, old_state=None, new_state=<state group.all_switches=unknown; entity_id=('switch.visonic_alarm_panel',), order=0, auto=True, friendly_name=all switches, hidden=True @ 2018-07-21T21:30:16.400864+00:00>>
2018-07-21 21:30:16 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_executed[L]: service_call_id=548372961432-2>
2018-07-21 21:30:16 DEBUG (zeroconf-ServiceBrowser__googlecast._tcp.local.) [homeassistant.components.media_player.cast] Discovered chromecast ChromecastInfo(host='192.168.0.6', port=8009, uuid='64bbc06a-e3cf-bbcc-9b58-2b9429414eac', model_name='SHIELD Android TV', friendly_name='SHIELD')
2018-07-21 21:30:16 DEBUG (zeroconf-ServiceBrowser__googlecast._tcp.local.) [homeassistant.components.media_player.cast] Discovered chromecast ChromecastInfo(host='192.168.0.9', port=8009, uuid='472c8007-ab73-0c69-3acf-507cd982bf60', model_name='Google Home', friendly_name='Living Room speaker')
2018-07-21 21:30:16 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=media_player.shield, old_state=None, new_state=<state media_player.shield=unavailable; friendly_name=SHIELD, supported_features=21437 @ 2018-07-21T21:30:16.665833+00:00>>
2018-07-21 21:30:16 DEBUG (MainThread) [homeassistant.components.media_player.cast] Connecting to cast device ChromecastInfo(host='192.168.0.6', port=8009, uuid='64bbc06a-e3cf-bbcc-9b58-2b9429414eac', model_name='SHIELD Android TV', friendly_name='SHIELD')
2018-07-21 21:30:16 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=media_player.living_room_speaker, old_state=None, new_state=<state media_player.living_room_speaker=unavailable; friendly_name=Living Room speaker, supported_features=21437 @ 2018-07-21T21:30:16.681125+00:00>>
2018-07-21 21:30:16 INFO (SyncWorker_10) [pychromecast] Querying device status
2018-07-21 21:30:16 DEBUG (MainThread) [homeassistant.components.media_player.cast] Connecting to cast device ChromecastInfo(host='192.168.0.9', port=8009, uuid='472c8007-ab73-0c69-3acf-507cd982bf60', model_name='Google Home', friendly_name='Living Room speaker')
2018-07-21 21:30:16 INFO (SyncWorker_5) [pychromecast] Querying device status
2018-07-21 21:30:16 DEBUG (SyncWorker_10) [pychromecast.controllers] Receiver:Updating status
2018-07-21 21:30:16 DEBUG (SyncWorker_10) [pychromecast.socket_client] Sending: Message urn:x-cast:com.google.cast.tp.connection from sender-0 to receiver-0: {'type': 'CONNECT', 'origin': {}, 'userAgent': 'PyChromecast', 'senderInfo': {'sdkType': 2, 'version': '15.605.1.3', 'browserVersion': '44.0.2403.30', 'platform': 4, 'systemVersion': 'Macintosh; Intel Mac OS X10_10_3', 'connectionType': 1}}
2018-07-21 21:30:16 DEBUG (SyncWorker_10) [pychromecast.socket_client] Sending: Message urn:x-cast:com.google.cast.receiver from sender-0 to receiver-0: {'type': 'GET_STATUS', 'requestId': 1}
2018-07-21 21:30:16 DEBUG (SyncWorker_10) [pychromecast.socket_client] Connected!
2018-07-21 21:30:16 DEBUG (Thread-2) [root] Thread started...
2018-07-21 21:30:16 DEBUG (MainThread) [homeassistant.components.media_player.cast] Connection successful!
2018-07-21 21:30:16 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=media_player.shield, old_state=<state media_player.shield=unavailable; friendly_name=SHIELD, supported_features=21437 @ 2018-07-21T21:30:16.665833+00:00>, new_state=<state media_player.shield=off; friendly_name=SHIELD, supported_features=21437 @ 2018-07-21T21:30:16.905302+00:00>>
2018-07-21 21:30:16 DEBUG (Thread-2) [pychromecast.socket_client] Received: Message urn:x-cast:com.google.cast.receiver from receiver-0 to sender-0: {'requestId': 1, 'status': {'isActiveInput': True, 'isStandBy': False, 'userEq': {}, 'volume': {'controlType': 'unknown', 'level': 1.0, 'muted': False, 'stepInterval': 0.009999999776482582}}, 'type': 'RECEIVER_STATUS'}
2018-07-21 21:30:16 DEBUG (Thread-2) [pychromecast.controllers] Received status: CastStatus(is_active_input=True, is_stand_by=False, volume_level=1.0, volume_muted=False, app_id=None, display_name=None, namespaces=[], session_id=None, transport_id=None, status_text='')
2018-07-21 21:30:17 DEBUG (SyncWorker_5) [pychromecast.controllers] Receiver:Updating status
2018-07-21 21:30:17 DEBUG (SyncWorker_5) [pychromecast.socket_client] Sending: Message urn:x-cast:com.google.cast.tp.connection from sender-0 to receiver-0: {'type': 'CONNECT', 'origin': {}, 'userAgent': 'PyChromecast', 'senderInfo': {'sdkType': 2, 'version': '15.605.1.3', 'browserVersion': '44.0.2403.30', 'platform': 4, 'systemVersion': 'Macintosh; Intel Mac OS X10_10_3', 'connectionType': 1}}
2018-07-21 21:30:17 DEBUG (SyncWorker_5) [pychromecast.socket_client] Sending: Message urn:x-cast:com.google.cast.receiver from sender-0 to receiver-0: {'type': 'GET_STATUS', 'requestId': 1}
2018-07-21 21:30:17 DEBUG (SyncWorker_5) [pychromecast.socket_client] Connected!
2018-07-21 21:30:17 DEBUG (Thread-3) [root] Thread started...
2018-07-21 21:30:17 DEBUG (MainThread) [homeassistant.components.media_player.cast] Connection successful!
2018-07-21 21:30:17 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=media_player.living_room_speaker, old_state=<state media_player.living_room_speaker=unavailable; friendly_name=Living Room speaker, supported_features=21437 @ 2018-07-21T21:30:16.681125+00:00>, new_state=<state media_player.living_room_speaker=off; friendly_name=Living Room speaker, supported_features=21437 @ 2018-07-21T21:30:17.173610+00:00>>
2018-07-21 21:30:17 DEBUG (Thread-3) [pychromecast.socket_client] Received: Message urn:x-cast:com.google.cast.receiver from receiver-0 to sender-0: {'requestId': 1, 'status': {'userEq': {'high_shelf': {'frequency': 4500.0, 'gain_db': 0.0, 'quality': 0.707}, 'low_shelf': {'frequency': 150.0, 'gain_db': 0.0, 'quality': 0.707}, 'max_peaking_eqs': 0, 'peaking_eqs': []}, 'volume': {'controlType': 'master', 'level': 0.30454543232917786, 'muted': False, 'stepInterval': 0.019999999552965164}}, 'type': 'RECEIVER_STATUS'}
2018-07-21 21:30:17 DEBUG (Thread-3) [pychromecast.controllers] Received status: CastStatus(is_active_input=None, is_stand_by=None, volume_level=0.30454543232917786, volume_muted=False, app_id=None, display_name=None, namespaces=[], session_id=None, transport_id=None, status_text='')
2018-07-21 21:30:18 INFO (MainThread) [custom_components.pyvisonic] [Startup]   count is 1
2018-07-21 21:30:18 INFO (MainThread) [custom_components.pyvisonic] [Start_Download] Starting download mode
2018-07-21 21:30:18 INFO (MainThread) [custom_components.pyvisonic] [QueueMessage] Start Download Mode
2018-07-21 21:30:18 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Start Download Mode)    raw data 0d 24 00 00 56 50 00 00 00 00 00 00 35 0a 
2018-07-21 21:30:18 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:18 INFO (MainThread) [custom_components.pyvisonic] [handle_msgtype08] Access Denied  len 0 data 
2018-07-21 21:30:18 INFO (MainThread) [custom_components.pyvisonic] [handle_msgtypeAB]  data 0a 00 01 00 00 00 00 00 00 00 43 
2018-07-21 21:30:18 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack Long)    raw data 0d 02 43 ba 0a 
2018-07-21 21:30:18 INFO (MainThread) [custom_components.pyvisonic] [handle_msgtypeAB] PowerLink most likely wants to auto-enroll, only doing auto enroll once
2018-07-21 21:30:19 INFO (MainThread) [custom_components.pyvisonic] [SendMsg_ENROLL]  download pin will be 56 50 
2018-07-21 21:30:19 INFO (MainThread) [custom_components.pyvisonic] [QueueMessage] Auto-Enroll of the PowerMax/Master
2018-07-21 21:30:19 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Auto-Enroll of the PowerMax/Master)    raw data 0d ab 0a 00 00 56 50 00 00 00 00 00 43 60 0a 
2018-07-21 21:30:19 INFO (MainThread) [custom_components.pyvisonic] [Start_Download] Starting download mode
2018-07-21 21:30:19 INFO (MainThread) [custom_components.pyvisonic] [QueueMessage] Start Download Mode
2018-07-21 21:30:19 INFO (MainThread) [homeassistant.setup] Setting up updater
2018-07-21 21:30:19 INFO (MainThread) [homeassistant.setup] Setup of domain updater took 0.0 seconds.
2018-07-21 21:30:19 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=updater>
2018-07-21 21:30:19 INFO (MainThread) [custom_components.pyvisonic] [handle_msgtypeAB]  data 03 00 1e 00 31 2e 30 37 00 00 43 
2018-07-21 21:30:19 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack Long)    raw data 0d 02 43 ba 0a 
2018-07-21 21:30:19 INFO (MainThread) [custom_components.pyvisonic] [handle_msgtypeAB] ***************************** Got PowerLink Keep-Alive ****************************
2018-07-21 21:30:19 INFO (MainThread) [custom_components.pyvisonic] [handle_msgtypeAB]         Got alive message while not in Powerlink mode but we're in Download mode
2018-07-21 21:30:19 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:19 INFO (MainThread) [custom_components.pyvisonic] [handle_msgtypeA7] Panel Status Change 01 00 07 06 00 ff 00 37 00 00 43 
2018-07-21 21:30:19 INFO (MainThread) [custom_components.pyvisonic] [handle_msgtypeA7]         System message Tamper / Zone 07  alarmStatus Tamper   troubleStatus None
2018-07-21 21:30:19 INFO (MainThread) [custom_components.visonic] Visonic update event 3
2018-07-21 21:30:19 INFO (MainThread) [homeassistant.core] Bus:Handling <Event alarm_panel_state_update[L]: condition=3>
2018-07-21 21:30:19 INFO (SyncWorker_12) [custom_components.alarm_control_panel.visonic] alarm control panel received update event
2018-07-21 21:30:19 INFO (SyncWorker_6) [custom_components.switch.visonic] alarm state panel received update event
2018-07-21 21:30:19 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:20 INFO (MainThread) [custom_components.pyvisonic] [handle_msgtypeA7] Panel Status Change 01 00 07 16 00 ff 00 37 00 00 43 
2018-07-21 21:30:20 INFO (MainThread) [custom_components.pyvisonic] [handle_msgtypeA7]         System message Tamper Restore / Zone 07  alarmStatus None   troubleStatus None
2018-07-21 21:30:20 INFO (MainThread) [custom_components.visonic] Visonic update event 3
2018-07-21 21:30:20 INFO (MainThread) [homeassistant.core] Bus:Handling <Event alarm_panel_state_update[L]: condition=3>
2018-07-21 21:30:20 INFO (SyncWorker_13) [custom_components.alarm_control_panel.visonic] alarm control panel received update event
2018-07-21 21:30:20 INFO (SyncWorker_14) [custom_components.switch.visonic] alarm state panel received update event
2018-07-21 21:30:20 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:20 INFO (MainThread) [custom_components.pyvisonic] [handle_msgtypeA7] Panel Status Change 01 00 07 06 00 ff 00 37 00 00 43 
2018-07-21 21:30:20 INFO (MainThread) [custom_components.pyvisonic] [handle_msgtypeA7]         System message Tamper / Zone 07  alarmStatus Tamper   troubleStatus None
2018-07-21 21:30:20 INFO (MainThread) [custom_components.visonic] Visonic update event 3
2018-07-21 21:30:20 INFO (MainThread) [homeassistant.core] Bus:Handling <Event alarm_panel_state_update[L]: condition=3>
2018-07-21 21:30:20 INFO (SyncWorker_3) [custom_components.alarm_control_panel.visonic] alarm control panel received update event
2018-07-21 21:30:20 INFO (SyncWorker_7) [custom_components.switch.visonic] alarm state panel received update event
2018-07-21 21:30:20 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:20 INFO (MainThread) [custom_components.pyvisonic] [handle_msgtypeA7] Panel Status Change 01 00 07 16 00 ff 00 37 00 00 43 
2018-07-21 21:30:20 INFO (MainThread) [custom_components.pyvisonic] [handle_msgtypeA7]         System message Tamper Restore / Zone 07  alarmStatus None   troubleStatus None
2018-07-21 21:30:20 INFO (MainThread) [custom_components.visonic] Visonic update event 3
2018-07-21 21:30:20 INFO (MainThread) [homeassistant.core] Bus:Handling <Event alarm_panel_state_update[L]: condition=3>
2018-07-21 21:30:20 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Start Download Mode)    raw data 0d 24 00 00 56 50 00 00 00 00 00 00 35 0a 
2018-07-21 21:30:20 INFO (SyncWorker_16) [custom_components.alarm_control_panel.visonic] alarm control panel received update event
2018-07-21 21:30:20 INFO (SyncWorker_15) [custom_components.switch.visonic] alarm state panel received update event
2018-07-21 21:30:20 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:20 INFO (MainThread) [custom_components.pyvisonic] [Enrolling Powerlink] Reading panel settings
2018-07-21 21:30:20 INFO (MainThread) [custom_components.pyvisonic] [QueueMessage] Download Data Set
2018-07-21 21:30:20 INFO (MainThread) [custom_components.pyvisonic] [QueueMessage] Download Data Set
2018-07-21 21:30:20 INFO (MainThread) [custom_components.pyvisonic] [QueueMessage] Download Data Set
2018-07-21 21:30:20 INFO (MainThread) [custom_components.pyvisonic] [QueueMessage] Start
2018-07-21 21:30:20 INFO (MainThread) [custom_components.pyvisonic] [QueueMessage] Exit
2018-07-21 21:30:20 INFO (MainThread) [custom_components.pyvisonic] [QueueMessage] Setting Time
2018-07-21 21:30:21 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Download Data Set)    raw data 0d 3e 00 04 20 00 b0 00 00 00 00 00 ec 0a 
2018-07-21 21:30:21 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:21 INFO (MainThread) [custom_components.pyvisonic] [handle_msgtype3F]
2018-07-21 21:30:22 INFO (MainThread) [homeassistant.setup] Setting up discovery
2018-07-21 21:30:22 INFO (MainThread) [homeassistant.setup] Setup of domain discovery took 0.1 seconds.
2018-07-21 21:30:22 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=discovery>
2018-07-21 21:30:22 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Download Data Set)    raw data 0d 3e 30 04 08 00 b0 00 00 00 00 00 d4 0a 
2018-07-21 21:30:22 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:22 INFO (MainThread) [custom_components.pyvisonic] [handle_msgtype3F]
2018-07-21 21:30:23 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Download Data Set)    raw data 0d 3e 00 19 00 02 b0 00 00 00 00 00 f5 0a 
2018-07-21 21:30:23 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:24 INFO (MainThread) [custom_components.pyvisonic] [handle_msgtype3F]
2018-07-21 21:30:24 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:24 INFO (MainThread) [custom_components.pyvisonic] [handle_msgtype3F]
2018-07-21 21:30:24 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:24 INFO (MainThread) [custom_components.pyvisonic] [handle_msgtype3F]
2018-07-21 21:30:24 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Start)    raw data 0d 0a f5 0a 
2018-07-21 21:30:24 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:24 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:24 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:25 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:25 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:25 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:25 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:25 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:25 INFO (MainThread) [homeassistant.components.sensor] Setting up sensor.yr
2018-07-21 21:30:25 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:26 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:26 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:26 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.yr_symbol, old_state=None, new_state=<state sensor.yr_symbol=unknown; attribution=Weather forecast from met.no, delivered by the Norwegian Meteorological Institute., friendly_name=yr Symbol, entity_picture=https://api.met.no/weatherapi/weathericon/1.1/?symbol=None;content_type=image/png @ 2018-07-21T21:30:26.580189+00:00>>
2018-07-21 21:30:26 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:26 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:26 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:27 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:27 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:27 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:27 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:27 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:27 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:27 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:28 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:29 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:29 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.yr_symbol, old_state=<state sensor.yr_symbol=unknown; attribution=Weather forecast from met.no, delivered by the Norwegian Meteorological Institute., friendly_name=yr Symbol, entity_picture=https://api.met.no/weatherapi/weathericon/1.1/?symbol=None;content_type=image/png @ 2018-07-21T21:30:26.580189+00:00>, new_state=<state sensor.yr_symbol=3; attribution=Weather forecast from met.no, delivered by the Norwegian Meteorological Institute., friendly_name=yr Symbol, entity_picture=https://api.met.no/weatherapi/weathericon/1.1/?symbol=3;content_type=image/png @ 2018-07-21T21:30:29.275647+00:00>>
2018-07-21 21:30:29 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:29 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:29 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:29 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:29 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:29 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:29 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:30 INFO (MainThread) [homeassistant.setup] Setup of domain sensor took 28.9 seconds.
2018-07-21 21:30:30 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=sensor>
2018-07-21 21:30:30 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:30 INFO (MainThread) [homeassistant.setup] Setting up cloud
2018-07-21 21:30:30 INFO (MainThread) [homeassistant.setup] Setup of domain cloud took 0.0 seconds.
2018-07-21 21:30:30 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=cloud>
2018-07-21 21:30:30 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:30 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:30 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:30 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:30 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:30 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:30 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:31 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:31 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:31 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:31 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:31 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:31 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:31 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:31 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:32 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:32 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:32 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:32 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:32 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:32 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:32 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:32 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:32 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:32 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:33 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:33 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:33 INFO (MainThread) [homeassistant.setup] Setting up tts
2018-07-21 21:30:33 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:33 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:33 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:33 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:33 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:34 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:34 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:34 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:34 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:34 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:34 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:34 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:34 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:35 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:35 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:35 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:35 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:35 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:35 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:35 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:35 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:35 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:35 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:36 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:36 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:36 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:36 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:36 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=config.config_entries>
2018-07-21 21:30:36 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:36 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:36 INFO (MainThread) [homeassistant.setup] Setup of domain config took 32.7 seconds.
2018-07-21 21:30:36 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=config>
2018-07-21 21:30:36 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:36 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:37 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:37 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:37 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:37 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:37 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:37 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:37 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:38 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:38 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:38 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:38 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:38 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:38 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:38 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:38 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:38 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:38 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:39 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:39 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:39 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:39 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:39 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:39 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:39 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=tts, service=google_say>
2018-07-21 21:30:39 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:39 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:39 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=tts, service=clear_cache>
2018-07-21 21:30:39 INFO (MainThread) [homeassistant.setup] Setup of domain tts took 6.6 seconds.
2018-07-21 21:30:39 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=tts>
2018-07-21 21:30:39 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:40 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:40 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:40 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:40 INFO (MainThread) [homeassistant.bootstrap] Home Assistant initialized in 57.47s
2018-07-21 21:30:40 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:40 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:40 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:40 INFO (MainThread) [homeassistant.core] Starting Home Assistant core loop
2018-07-21 21:30:40 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:40 INFO (MainThread) [homeassistant.core] Starting Home Assistant
2018-07-21 21:30:40 INFO (MainThread) [homeassistant.core] Bus:Handling <Event homeassistant_start[L]>
2018-07-21 21:30:40 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:40 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:41 INFO (MainThread) [homeassistant.components.discovery] Found new service: hassio {}
2018-07-21 21:30:41 INFO (MainThread) [homeassistant.core] Timer:starting
2018-07-21 21:30:41 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:41 INFO (MainThread) [homeassistant.loader] Loaded hassio from homeassistant.components.hassio
2018-07-21 21:30:41 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:41 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:41 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:41 INFO (MainThread) [homeassistant.setup] Setting up hassio
2018-07-21 21:30:41 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:41 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:41 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:42 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:42 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:42 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:42 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:42 INFO (MainThread) [homeassistant.loader] Loaded panel_custom from homeassistant.components.panel_custom
2018-07-21 21:30:42 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:42 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:42 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:43 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:43 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:43 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:43 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=hassio, service=addon_start>
2018-07-21 21:30:43 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=hassio, service=addon_stop>
2018-07-21 21:30:43 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=hassio, service=addon_restart>
2018-07-21 21:30:43 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=hassio, service=addon_stdin>
2018-07-21 21:30:43 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=hassio, service=host_shutdown>
2018-07-21 21:30:43 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=hassio, service=host_reboot>
2018-07-21 21:30:43 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=hassio, service=snapshot_full>
2018-07-21 21:30:43 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=hassio, service=snapshot_partial>
2018-07-21 21:30:43 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=hassio, service=restore_full>
2018-07-21 21:30:43 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=hassio, service=restore_partial>
2018-07-21 21:30:43 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:43 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:43 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=homeassistant, service=stop>
2018-07-21 21:30:43 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=homeassistant, service=restart>
2018-07-21 21:30:43 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=homeassistant, service=check_config>
2018-07-21 21:30:43 INFO (MainThread) [homeassistant.setup] Setup of domain hassio took 2.1 seconds.
2018-07-21 21:30:43 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=hassio>
2018-07-21 21:30:43 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:43 INFO (MainThread) [homeassistant.core] Bus:Handling <Event platform_discovered[L]: service=hassio, discovered=>
2018-07-21 21:30:43 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:43 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:44 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:44 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:44 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:44 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:44 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:44 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:44 INFO (MainThread) [homeassistant.components.http.view] Serving /api/websocket to 192.168.0.29 (auth: True)
2018-07-21 21:30:44 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Connected 
2018-07-21 21:30:44 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Auth OK 
2018-07-21 21:30:44 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:44 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:44 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:45 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:45 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Received {'type': 'subscribe_events', 'event_type': 'state_changed', 'id': 2}
2018-07-21 21:30:45 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Received {'type': 'subscribe_events', 'event_type': 'component_loaded', 'id': 3}
2018-07-21 21:30:45 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Received {'type': 'subscribe_events', 'event_type': 'service_registered', 'id': 4}
2018-07-21 21:30:45 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Received {'type': 'subscribe_events', 'event_type': 'service_removed', 'id': 5}
2018-07-21 21:30:45 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Received {'type': 'subscribe_events', 'event_type': 'themes_updated', 'id': 6}
2018-07-21 21:30:45 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Received {'type': 'get_states', 'id': 7}
2018-07-21 21:30:45 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Received {'type': 'frontend/get_translations', 'language': 'sv', 'id': 8}
2018-07-21 21:30:45 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Received {'type': 'get_panels', 'id': 9}
2018-07-21 21:30:45 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Received {'type': 'get_config', 'id': 10}
2018-07-21 21:30:45 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Received {'type': 'get_panels', 'id': 11}
2018-07-21 21:30:45 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Received {'type': 'get_services', 'id': 12}
2018-07-21 21:30:45 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:45 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 2, 'type': 'result', 'success': True, 'result': None}
2018-07-21 21:30:45 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 3, 'type': 'result', 'success': True, 'result': None}
2018-07-21 21:30:45 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 4, 'type': 'result', 'success': True, 'result': None}
2018-07-21 21:30:45 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 5, 'type': 'result', 'success': True, 'result': None}
2018-07-21 21:30:45 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 6, 'type': 'result', 'success': True, 'result': None}
2018-07-21 21:30:45 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 7, 'type': 'result', 'success': True, 'result': [<state persistent_notification.notification=notifying; title=Welcome Home!, message=Here are some resources to get started:

 - [Configuring Home Assistant](https://home-assistant.io/getting-started/configuration/)
 - [Available components](https://home-assistant.io/components/)
 - [Troubleshooting your configuration](https://home-assistant.io/docs/configuration/troubleshooting/)
 - [Getting help](https://home-assistant.io/help/)

To not see this card popup in the future, edit your config in
`configuration.yaml` and disable the `introduction` component. @ 2018-07-21T21:29:45.613855+00:00>, <state sun.sun=below_horizon; next_dawn=2018-07-22T05:40:51+00:00, next_dusk=2018-07-22T18:32:03+00:00, next_midnight=2018-07-22T00:06:28+00:00, next_noon=2018-07-22T12:06:27+00:00, next_rising=2018-07-22T06:02:54+00:00, next_setting=2018-07-22T18:10:00+00:00, elevation=-46.75, azimuth=300.21, friendly_name=Sun @ 2018-07-21T21:30:03.875818+00:00>, <state alarm_control_panel.visonic_alarm=unknown; code_format=None, changed_by=None, friendly_name=Visonic Alarm @ 2018-07-21T21:30:16.336204+00:00>, <state switch.visonic_alarm_panel=standby; PluginVersion=0.0.1, CommExceptionCount=0, Mode=Download, PanelLastEvent=Tamper Restore / Zone 07, PanelAlarmStatus=None, PanelTroubleStatus=None, PanelSirenActive=True, PanelStatus=Unknown, PanelStatusCode=-1, PanelReady=False, PanelAlertInMemory=False, PanelTrouble=False, PanelBypass=False, PanelStatusChanged=False, PanelAlarmEvent=False, Model=, ModelType=79, PanelArmed=False, PowerMaster=False, PanelSoftware=Unknown, PanelName=Unknown, PanelSerial=Unknown, DoorZones=Unknown, MotionZones=Unknown, SmokeZones=Unknown, OtherZones=Unknown, Devices=Unknown, PhoneNumbers=, EntryTime1=0, EntryTime2=0, ExitTime=0, BellTime=0, SilentPanic=False, QuickArm=False, BypassOff=False, friendly_name=Visonic Alarm Panel @ 2018-07-21T21:30:16.370850+00:00>, <state group.all_switches=unknown; entity_id=('switch.visonic_alarm_panel',), order=0, auto=True, friendly_name=all switches, hidden=True @ 2018-07-21T21:30:16.400864+00:00>, <state media_player.shield=off; friendly_name=SHIELD, supported_features=21437 @ 2018-07-21T21:30:16.905302+00:00>, <state media_player.living_room_speaker=off; friendly_name=Living Room speaker, supported_features=21437 @ 2018-07-21T21:30:17.173610+00:00>, <state sensor.yr_symbol=3; attribution=Weather forecast from met.no, delivered by the Norwegian Meteorological Institute., friendly_name=yr Symbol, entity_picture=https://api.met.no/weatherapi/weathericon/1.1/?symbol=3;content_type=image/png @ 2018-07-21T21:30:29.275647+00:00>]}
2018-07-21 21:30:45 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 9, 'type': 'result', 'success': True, 'result': {'history': {'component_name': 'history', 'icon': 'hass:poll-box', 'title': 'history', 'config': None, 'url_path': 'history'}, 'kiosk': {'component_name': 'kiosk', 'icon': None, 'title': None, 'config': None, 'url_path': 'kiosk'}, 'lovelace': {'component_name': 'lovelace', 'icon': None, 'title': None, 'config': None, 'url_path': 'lovelace'}, 'dev-state': {'component_name': 'dev-state', 'icon': None, 'title': None, 'config': None, 'url_path': 'dev-state'}, 'dev-event': {'component_name': 'dev-event', 'icon': None, 'title': None, 'config': None, 'url_path': 'dev-event'}, 'dev-template': {'component_name': 'dev-template', 'icon': None, 'title': None, 'config': None, 'url_path': 'dev-template'}, 'profile': {'component_name': 'profile', 'icon': None, 'title': None, 'config': None, 'url_path': 'profile'}, 'dev-info': {'component_name': 'dev-info', 'icon': None, 'title': None, 'config': None, 'url_path': 'dev-info'}, 'dev-mqtt': {'component_name': 'dev-mqtt', 'icon': None, 'title': None, 'config': None, 'url_path': 'dev-mqtt'}, 'dev-service': {'component_name': 'dev-service', 'icon': None, 'title': None, 'config': None, 'url_path': 'dev-service'}, 'map': {'component_name': 'map', 'icon': 'mdi:account-location', 'title': 'map', 'config': None, 'url_path': 'map'}, 'logbook': {'component_name': 'logbook', 'icon': 'hass:format-list-bulleted-type', 'title': 'logbook', 'config': None, 'url_path': 'logbook'}, 'config': {'component_name': 'config', 'icon': 'hass:settings', 'title': 'config', 'config': None, 'url_path': 'config'}, 'hassio': {'component_name': 'custom', 'icon': 'hass:home-assistant', 'title': 'Hass.io', 'config': {'_panel_custom': {'name': 'hassio-main', 'embed_iframe': True, 'trust_external': False, 'js_url': '/api/hassio/app/entrypoint.js'}}, 'url_path': 'hassio'}}}
2018-07-21 21:30:45 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 10, 'type': 'result', 'success': True, 'result': {'latitude': 0.0, 'longitude': 0.0, 'elevation': 0, 'unit_system': {'length': 'km', 'mass': 'g', 'temperature': '°C', 'volume': 'L'}, 'location_name': 'Home', 'time_zone': 'UTC', 'components': {'config.group', 'config.core', 'config.customize', 'switch', 'config.entity_registry', 'visonic', 'cast', 'updater', 'logbook', 'config.script', 'recorder', 'config', 'map', 'cloud', 'group', 'logger', 'conversation', 'automation', 'config.automation', 'media_player.cast', 'sensor.yr', 'api', 'system_log', 'http', 'websocket_api', 'frontend', 'script', 'alarm_control_panel', 'hassio', 'introduction', 'alarm_control_panel.visonic', 'tts', 'switch.visonic', 'sun', 'history', 'sensor', 'onboarding', 'config.config_entries', 'discovery', 'media_player'}, 'config_dir': '/config', 'whitelist_external_dirs': {'/config/www'}, 'version': '0.74.0'}}
2018-07-21 21:30:45 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 11, 'type': 'result', 'success': True, 'result': {'history': {'component_name': 'history', 'icon': 'hass:poll-box', 'title': 'history', 'config': None, 'url_path': 'history'}, 'kiosk': {'component_name': 'kiosk', 'icon': None, 'title': None, 'config': None, 'url_path': 'kiosk'}, 'lovelace': {'component_name': 'lovelace', 'icon': None, 'title': None, 'config': None, 'url_path': 'lovelace'}, 'dev-state': {'component_name': 'dev-state', 'icon': None, 'title': None, 'config': None, 'url_path': 'dev-state'}, 'dev-event': {'component_name': 'dev-event', 'icon': None, 'title': None, 'config': None, 'url_path': 'dev-event'}, 'dev-template': {'component_name': 'dev-template', 'icon': None, 'title': None, 'config': None, 'url_path': 'dev-template'}, 'profile': {'component_name': 'profile', 'icon': None, 'title': None, 'config': None, 'url_path': 'profile'}, 'dev-info': {'component_name': 'dev-info', 'icon': None, 'title': None, 'config': None, 'url_path': 'dev-info'}, 'dev-mqtt': {'component_name': 'dev-mqtt', 'icon': None, 'title': None, 'config': None, 'url_path': 'dev-mqtt'}, 'dev-service': {'component_name': 'dev-service', 'icon': None, 'title': None, 'config': None, 'url_path': 'dev-service'}, 'map': {'component_name': 'map', 'icon': 'mdi:account-location', 'title': 'map', 'config': None, 'url_path': 'map'}, 'logbook': {'component_name': 'logbook', 'icon': 'hass:format-list-bulleted-type', 'title': 'logbook', 'config': None, 'url_path': 'logbook'}, 'config': {'component_name': 'config', 'icon': 'hass:settings', 'title': 'config', 'config': None, 'url_path': 'config'}, 'hassio': {'component_name': 'custom', 'icon': 'hass:home-assistant', 'title': 'Hass.io', 'config': {'_panel_custom': {'name': 'hassio-main', 'embed_iframe': True, 'trust_external': False, 'js_url': '/api/hassio/app/entrypoint.js'}}, 'url_path': 'hassio'}}}
2018-07-21 21:30:45 INFO (MainThread) [homeassistant.loader] Loaded deconz from homeassistant.components.deconz
2018-07-21 21:30:45 INFO (MainThread) [homeassistant.loader] Loaded hue from homeassistant.components.hue
2018-07-21 21:30:45 INFO (MainThread) [homeassistant.loader] Loaded nest from homeassistant.components.nest
2018-07-21 21:30:45 INFO (MainThread) [homeassistant.loader] Loaded homematicip_cloud from homeassistant.components.homematicip_cloud
2018-07-21 21:30:45 INFO (MainThread) [homeassistant.loader] Loaded sonos from homeassistant.components.sonos
2018-07-21 21:30:45 INFO (MainThread) [homeassistant.loader] Loaded zone from homeassistant.components.zone
2018-07-21 21:30:46 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:46 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/homematicip_cloud/.translations/sv.json
2018-07-21 21:30:46 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/config/.translations/group.sv.json
2018-07-21 21:30:46 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:46 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/config/.translations/core.sv.json
2018-07-21 21:30:46 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/config/.translations/customize.sv.json
2018-07-21 21:30:46 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/switch/.translations/sv.json
2018-07-21 21:30:46 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/config/.translations/entity_registry.sv.json
2018-07-21 21:30:46 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /config/custom_components/.translations/visonic.sv.json
2018-07-21 21:30:46 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:46 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/.translations/updater.sv.json
2018-07-21 21:30:46 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/.translations/logbook.sv.json
2018-07-21 21:30:46 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/config/.translations/script.sv.json
2018-07-21 21:30:46 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:46 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/recorder/.translations/sv.json
2018-07-21 21:30:46 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/config/.translations/sv.json
2018-07-21 21:30:46 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/.translations/map.sv.json
2018-07-21 21:30:46 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/cloud/.translations/sv.json
2018-07-21 21:30:46 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/group/.translations/sv.json
2018-07-21 21:30:46 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/.translations/logger.sv.json
2018-07-21 21:30:46 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:46 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/conversation/.translations/sv.json
2018-07-21 21:30:46 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/automation/.translations/sv.json
2018-07-21 21:30:46 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/config/.translations/automation.sv.json
2018-07-21 21:30:46 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/media_player/.translations/cast.sv.json
2018-07-21 21:30:46 INFO (MainThread) [homeassistant.components.http.view] Serving /api/hassio/app/entrypoint.js to 192.168.0.29 (auth: True)
2018-07-21 21:30:46 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:46 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/sensor/.translations/yr.sv.json
2018-07-21 21:30:46 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/.translations/api.sv.json
2018-07-21 21:30:46 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/system_log/.translations/sv.json
2018-07-21 21:30:46 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/http/.translations/sv.json
2018-07-21 21:30:46 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/.translations/websocket_api.sv.json
2018-07-21 21:30:46 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/frontend/.translations/sv.json
2018-07-21 21:30:46 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:46 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/.translations/script.sv.json
2018-07-21 21:30:47 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /config/custom_components/alarm_control_panel/.translations/sv.json
2018-07-21 21:30:47 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/hassio/.translations/sv.json
2018-07-21 21:30:47 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/.translations/introduction.sv.json
2018-07-21 21:30:47 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /config/custom_components/alarm_control_panel/.translations/visonic.sv.json
2018-07-21 21:30:47 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:47 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/tts/.translations/sv.json
2018-07-21 21:30:47 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /config/custom_components/switch/.translations/visonic.sv.json
2018-07-21 21:30:47 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/.translations/sun.sv.json
2018-07-21 21:30:47 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/.translations/history.sv.json
2018-07-21 21:30:47 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/sensor/.translations/sv.json
2018-07-21 21:30:47 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/onboarding/.translations/sv.json
2018-07-21 21:30:47 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:47 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/config/.translations/config_entries.sv.json
2018-07-21 21:30:47 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/.translations/discovery.sv.json
2018-07-21 21:30:47 DEBUG (SyncWorker_11) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/media_player/.translations/sv.json
2018-07-21 21:30:47 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:47 INFO (MainThread) [homeassistant.components.http.view] Serving /api/hassio/app/chunk.f3880aa331d3ef2ddf32.js to 192.168.0.29 (auth: True)
2018-07-21 21:30:47 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:47 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/config/.translations/group.en.json
2018-07-21 21:30:47 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/config/.translations/core.en.json
2018-07-21 21:30:47 INFO (MainThread) [homeassistant.components.http.view] Serving /api/hassio/app/chunk.ff92199b0d422767d108.js to 192.168.0.29 (auth: True)
2018-07-21 21:30:47 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/config/.translations/customize.en.json
2018-07-21 21:30:47 INFO (MainThread) [homeassistant.components.http.view] Serving /api/hassio/app/chunk.a8e86d80be46b3b6e16d.js to 192.168.0.29 (auth: True)
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/switch/.translations/en.json
2018-07-21 21:30:48 INFO (MainThread) [homeassistant.components.http.view] Serving /api/hassio/app/chunk.0ef4ef1053fe3d5107b5.js to 192.168.0.29 (auth: True)
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/config/.translations/entity_registry.en.json
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /config/custom_components/.translations/visonic.en.json
2018-07-21 21:30:48 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/.translations/updater.en.json
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/.translations/logbook.en.json
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/config/.translations/script.en.json
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/recorder/.translations/en.json
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/config/.translations/en.json
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/.translations/map.en.json
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/cloud/.translations/en.json
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/group/.translations/en.json
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/.translations/logger.en.json
2018-07-21 21:30:48 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/conversation/.translations/en.json
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/automation/.translations/en.json
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/config/.translations/automation.en.json
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/media_player/.translations/cast.en.json
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/sensor/.translations/yr.en.json
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/.translations/api.en.json
2018-07-21 21:30:48 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/system_log/.translations/en.json
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/http/.translations/en.json
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/.translations/websocket_api.en.json
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/frontend/.translations/en.json
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/.translations/script.en.json
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /config/custom_components/alarm_control_panel/.translations/en.json
2018-07-21 21:30:48 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/hassio/.translations/en.json
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/.translations/introduction.en.json
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /config/custom_components/alarm_control_panel/.translations/visonic.en.json
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/tts/.translations/en.json
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /config/custom_components/switch/.translations/visonic.en.json
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/.translations/sun.en.json
2018-07-21 21:30:48 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/.translations/history.en.json
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/sensor/.translations/en.json
2018-07-21 21:30:48 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/onboarding/.translations/en.json
2018-07-21 21:30:49 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/config/.translations/config_entries.en.json
2018-07-21 21:30:49 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/.translations/discovery.en.json
2018-07-21 21:30:49 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:49 DEBUG (SyncWorker_6) [homeassistant.util.json] JSON file not found: /usr/local/lib/python3.6/site-packages/homeassistant/components/media_player/.translations/en.json
2018-07-21 21:30:49 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:49 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:49 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 8, 'type': 'result', 'success': True, 'result': {'resources': {'component.deconz.config.abort.already_configured': 'Bryggan är redan konfigurerad', 'component.deconz.config.abort.no_bridges': 'Inga deCONZ-bryggor upptäcktes', 'component.deconz.config.abort.one_instance_only': 'Komponenten stödjer endast en deCONZ-instans', 'component.deconz.config.error.no_key': 'Det gick inte att ta emot en API-nyckel', 'component.deconz.config.step.init.data.host': 'Värd', 'component.deconz.config.step.init.data.port': "Port (standardvärde: '80')", 'component.deconz.config.step.init.title': 'Definiera deCONZ-gatewaye', 'component.deconz.config.step.link.description': 'Lås upp din deCONZ-gateway för att registrera dig med Home Assistant. \n\n 1. Gå till deCONZ-systeminställningarna \n 2. Tryck på "Lås upp gateway"-knappen', 'component.deconz.config.step.link.title': 'Länka med deCONZ', 'component.deconz.config.step.options.data.allow_clip_sensor': 'Tillåt import av virtuella sensorer', 'component.deconz.config.step.options.data.allow_deconz_groups': 'Tillåt import av deCONZ-grupper', 'component.deconz.config.step.options.title': 'Extra konfigurationsalternativ för deCONZ', 'component.deconz.config.title': 'deCONZ', 'component.hue.config.abort.all_configured': 'Alla Philips Hue-bryggor är redan konfigurerade', 'component.hue.config.abort.already_configured': 'Bryggan är redan konfigurerad', 'component.hue.config.abort.cannot_connect': 'Det gick inte att ansluta till bryggan', 'component.hue.config.abort.discover_timeout': 'Det gick inte att upptäcka några Hue-bryggor', 'component.hue.config.abort.no_bridges': 'Inga Philips Hue-bryggor upptäcktes', 'component.hue.config.abort.unknown': 'Ett okänt fel inträffade', 'component.hue.config.error.linking': 'Ett okänt länkningsfel inträffade.', 'component.hue.config.error.register_failed': 'Misslyckades med att registrera, vänligen försök igen', 'component.hue.config.step.init.data.host': 'Värd', 'component.hue.config.step.init.title': 'Välj Hue-brygga', 'component.hue.config.step.link.description': 'Tryck på knappen på bryggan för att registrera Philips Hue med Home Assistant. \n\n ! [Placering av knapp på brygga] (/ static / images / config_philips_hue.jpg)', 'component.hue.config.step.link.title': 'Länka hub', 'component.hue.config.title': 'Philips Hue Brygga', 'component.nest.config.abort.already_setup': 'Du kan endast konfigurera ett Nest-konto.', 'component.nest.config.abort.authorize_url_fail': 'Okänt fel vid generering av autentisieringsadress.', 'component.nest.config.abort.authorize_url_timeout': 'Timeout vid generering av en autentisieringsadress.', 'component.nest.config.abort.no_flows': 'Du måste konfigurera Nest innan du kan autentisera med det. [Vänligen läs instruktionerna] (https://www.home-assistant.io/components/nest/).', 'component.nest.config.error.internal_error': 'Internt fel vid validering av kod', 'component.nest.config.error.invalid_code': 'Ogiltig kod', 'component.nest.config.error.timeout': 'Timeout vid valididering av kod', 'component.nest.config.error.unknown': 'Okänt fel vid validering av kod', 'component.nest.config.step.init.data.flow_impl': 'Leverantör', 'component.nest.config.step.init.description': 'Välj den autentiseringsleverantör som du vill autentisera med mot Nest.', 'component.nest.config.step.init.title': 'Autentiseringsleverantör', 'component.nest.config.step.link.data.code': 'Pin-kod', 'component.nest.config.step.link.description': 'För att länka ditt Nest-konto, [autentisiera ditt konto]({url}). \n\nEfter autentisiering, klipp och klistra in den angivna pin-koden nedan.', 'component.nest.config.step.link.title': 'Länka Nest-konto', 'component.nest.config.title': 'Nest', 'component.homematicip_cloud.config.title': 'HomematicIP Cloud', 'component.homematicip_cloud.config.step.init.title': 'Pick HomematicIP Accesspoint', 'component.homematicip_cloud.config.step.init.data.hapid': 'Accesspoint ID (SGTIN)', 'component.homematicip_cloud.config.step.init.data.pin': 'Pin Code (optional)', 'component.homematicip_cloud.config.step.init.data.name': 'Name (optional, used as name prefix for all devices)', 'component.homematicip_cloud.config.step.link.title': 'Link Accesspoint', 'component.homematicip_cloud.config.step.link.description': 'Press the blue button on the accesspoint and the submit button to register HomematicIP with Home Assistant.\n\n![Location of button on bridge](/static/images/config_flows/config_homematicip_cloud.png)', 'component.homematicip_cloud.config.error.register_failed': 'Failed to register, please try again.', 'component.homematicip_cloud.config.error.invalid_pin': 'Invalid PIN, please try again.', 'component.homematicip_cloud.config.error.press_the_button': 'Please press the blue button.', 'component.homematicip_cloud.config.error.timeout_button': 'Blue button press timeout, please try again.', 'component.homematicip_cloud.config.abort.unknown': 'Unknown error occurred.', 'component.homematicip_cloud.config.abort.conection_aborted': 'Could not connect to HMIP server', 'component.homematicip_cloud.config.abort.already_configured': 'Accesspoint is already configured', 'component.cast.config.abort.no_devices_found': 'Inga Google Cast-enheter hittades i nätverket.', 'component.cast.config.abort.single_instance_allowed': 'Endast en enda konfiguration av Google Cast är nödvändig.', 'component.cast.config.step.confirm.description': 'Vill du konfigurera Google Cast?', 'component.cast.config.step.confirm.title': 'Google Cast', 'component.cast.config.title': 'Google Cast', 'component.sonos.config.abort.no_devices_found': 'Inga Sonos-enheter hittades i nätverket.', 'component.sonos.config.abort.single_instance_allowed': 'Endast en enda konfiguration av Sonos är nödvändig.', 'component.sonos.config.step.confirm.description': 'Vill du konfigurera Sonos?', 'component.sonos.config.step.confirm.title': 'Sonos', 'component.sonos.config.title': 'Sonos', 'component.zone.config.error.name_exists': 'Namnet finns redan', 'component.zone.config.step.init.data.icon': 'Ikon', 'component.zone.config.step.init.data.latitude': 'Latitud', 'component.zone.config.step.init.data.longitude': 'Longitud', 'component.zone.config.step.init.data.name': 'Namn', 'component.zone.config.step.init.data.passive': 'Passiv', 'component.zone.config.step.init.data.radius': 'Radie', 'component.zone.config.step.init.title': 'Definiera zonparametrar', 'component.zone.config.title': 'Zon'}}}
2018-07-21 21:30:49 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:49 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:50 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:50 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:50 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:50 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:50 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:50 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:51 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:51 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:51 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:51 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:51 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:51 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:52 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:52 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:52 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:52 INFO (MainThread) [homeassistant.components.http.view] Serving /api/hassio/supervisor/info to 192.168.0.29 (auth: True)
2018-07-21 21:30:52 INFO (MainThread) [homeassistant.components.http.view] Serving /api/hassio/host/info to 192.168.0.29 (auth: True)
2018-07-21 21:30:52 INFO (MainThread) [homeassistant.components.http.view] Serving /api/hassio/homeassistant/info to 192.168.0.29 (auth: True)
2018-07-21 21:30:52 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:52 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:52 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 12, 'type': 'result', 'success': True, 'result': {'homeassistant': {'turn_off': {'description': 'Generic service to turn devices off under any domain. Same usage as the light.turn_on, switch.turn_on, etc. services.', 'fields': OrderedDict([('entity_id', OrderedDict([('description', 'The entity_id of the device to turn off.'), ('example', 'light.living_room')]))])}, 'turn_on': {'description': 'Generic service to turn devices on under any domain. Same usage as the light.turn_on, switch.turn_on, etc. services.', 'fields': OrderedDict([('entity_id', OrderedDict([('description', 'The entity_id of the device to turn on.'), ('example', 'light.living_room')]))])}, 'toggle': {'description': 'Generic service to toggle devices on/off under any domain. Same usage as the light.turn_on, switch.turn_on, etc. services.', 'fields': OrderedDict([('entity_id', OrderedDict([('description', 'The entity_id of the device to toggle on/off.'), ('example', 'light.living_room')]))])}, 'stop': {'description': 'Stop the Home Assistant service. It is normal to get a "Failed to call service homeassistant/stop" message.', 'fields': {}}, 'restart': {'description': 'Restart the Home Assistant service. It is normal to get a "Failed to call service homeassistant/restart" message.', 'fields': {}}, 'check_config': {'description': 'Check the Home Assistant configuration files for errors. Errors will be displayed in the Home Assistant log.', 'fields': {}}, 'reload_core_config': {'description': 'Reload the core configuration.', 'fields': {}}}, 'persistent_notification': {'create': {'description': 'Show a notification in the frontend.', 'fields': OrderedDict([('message', OrderedDict([('description', 'Message body of the notification. [Templates accepted]'), ('example', 'Please check your configuration.yaml.')])), ('title', OrderedDict([('description', 'Optional title for your notification. [Optional, Templates accepted]'), ('example', 'Test notification')])), ('notification_id', OrderedDict([('description', 'Target ID of the notification, will replace a notification with the same Id. [Optional]'), ('example', 1234)]))])}, 'dismiss': {'description': 'Remove a notification from the frontend.', 'fields': OrderedDict([('notification_id', OrderedDict([('description', 'Target ID of the notification, which should be removed. [Required]'), ('example', 1234)]))])}}, 'logger': {'set_default_level': {'description': 'Set the default log level for components.', 'fields': OrderedDict([('level', OrderedDict([('description', 'Default severity level. Possible values are notset, debug, info, warn, warning, error, fatal, critical'), ('example', 'debug')]))])}, 'set_level': {'description': 'Set log level for components.', 'fields': {}}}, 'recorder': {'purge': {'description': 'Start purge task - delete events and states older than x days, according to keep_days service data.', 'fields': OrderedDict([('keep_days', OrderedDict([('description', 'Number of history days to keep in database after purge. Value >= 0.'), ('example', 2)])), ('repack', OrderedDict([('description', 'Attempt to save disk space by rewriting the entire database file.'), ('example', True)]))])}}, 'system_log': {'clear': {'description': '', 'fields': {}}, 'write': {'description': '', 'fields': {}}}, 'group': {'reload': {'description': 'Reload group configuration.', 'fields': {}}, 'set': {'description': 'Create/Update a user group.', 'fields': OrderedDict([('object_id', OrderedDict([('description', 'Group id and part of entity id.'), ('example', 'test_group')])), ('name', OrderedDict([('description', 'Name of group'), ('example', 'My test group')])), ('view', OrderedDict([('description', 'Boolean for if the group is a view.'), ('example', True)])), ('icon', OrderedDict([('description', 'Name of icon for the group.'), ('example', 'mdi:camera')])), ('control', OrderedDict([('description', 'Value for control the group control.'), ('example', 'hidden')])), ('visible', OrderedDict([('description', 'If the group is visible on UI.'), ('example', True)])), ('entities', OrderedDict([('description', "List of all members in the group. Not compatible with 'delta'."), ('example', 'domain.entity_id1, domain.entity_id2')])), ('add_entities', OrderedDict([('description', 'List of members they will change on group listening.'), ('example', 'domain.entity_id1, domain.entity_id2')]))])}, 'remove': {'description': 'Remove a user group.', 'fields': OrderedDict([('object_id', OrderedDict([('description', 'Group id and part of entity id.'), ('example', 'test_group')]))])}, 'set_visibility': {'description': 'Hide or show a group.', 'fields': OrderedDict([('entity_id', OrderedDict([('description', 'Name(s) of entities to set value.'), ('example', 'group.travel')])), ('visible', OrderedDict([('description', 'True if group should be shown or False if it should be hidden.'), ('example', True)]))])}}, 'logbook': {'log': {'description': '', 'fields': {}}}, 'conversation': {'process': {'description': 'Launch a conversation from a transcribed text.', 'fields': OrderedDict([('text', OrderedDict([('description', 'Transcribed text'), ('example', 'Turn all lights on')]))])}}, 'automation': {'trigger': {'description': 'Trigger the action of an automation.', 'fields': OrderedDict([('entity_id', OrderedDict([('description', 'Name of the automation to trigger.'), ('example', 'automation.notify_home')]))])}, 'reload': {'description': 'Reload the automation configuration.', 'fields': {}}, 'toggle': {'description': 'Toggle an automation.', 'fields': OrderedDict([('entity_id', OrderedDict([('description', 'Name of the automation to toggle on/off.'), ('example', 'automation.notify_home')]))])}, 'turn_on': {'description': 'Enable an automation.', 'fields': OrderedDict([('entity_id', OrderedDict([('description', 'Name of the automation to turn on.'), ('example', 'automation.notify_home')]))])}, 'turn_off': {'description': 'Disable an automation.', 'fields': OrderedDict([('entity_id', OrderedDict([('description', 'Name of the automation to turn off.'), ('example', 'automation.notify_home')]))])}}, 'script': {'reload': {'description': '', 'fields': {}}, 'turn_on': {'description': '', 'fields': {}}, 'turn_off': {'description': '', 'fields': {}}, 'toggle': {'description': '', 'fields': {}}}, 'alarm_control_panel': {'alarm_disarm': {'description': '', 'fields': {}}, 'alarm_arm_home': {'description': '', 'fields': {}}, 'alarm_arm_away': {'description': '', 'fields': {}}, 'alarm_arm_night': {'description': '', 'fields': {}}, 'alarm_arm_custom_bypass': {'description': '', 'fields': {}}, 'alarm_trigger': {'description': '', 'fields': {}}}, 'switch': {'turn_off': {'description': 'Turn a switch off.', 'fields': OrderedDict([('entity_id', OrderedDict([('description', 'Name(s) of entities to turn off.'), ('example', 'switch.living_room')]))])}, 'turn_on': {'description': 'Turn a switch on.', 'fields': OrderedDict([('entity_id', OrderedDict([('description', 'Name(s) of entities to turn on'), ('example', 'switch.living_room')]))])}, 'toggle': {'description': 'Toggles a switch state.', 'fields': OrderedDict([('entity_id', OrderedDict([('description', 'Name(s) of entities to toggle.'), ('example', 'switch.living_room')]))])}}, 'media_player': {'turn_on': {'description': 'Turn a media player power on.', 'fields': OrderedDict([('entity_id', OrderedDict([('description', 'Name(s) of entities to turn on.'), ('example', 'media_player.living_room_chromecast')]))])}, 'turn_off': {'description': 'Turn a media player power off.', 'fields': OrderedDict([('entity_id', OrderedDict([('description', 'Name(s) of entities to turn off.'), ('example', 'media_player.living_room_chromecast')]))])}, 'toggle': {'description': 'Toggles a media player power state.', 'fields': OrderedDict([('entity_id', OrderedDict([('description', 'Name(s) of entities to toggle.'), ('example', 'media_player.living_room_chromecast')]))])}, 'volume_up': {'description': 'Turn a media player volume up.', 'fields': OrderedDict([('entity_id', OrderedDict([('description', 'Name(s) of entities to turn volume up on.'), ('example', 'media_player.living_room_sonos')]))])}, 'volume_down': {'description': 'Turn a media player volume down.', 'fields': OrderedDict([('entity_id', OrderedDict([('description', 'Name(s) of entities to turn volume down on.'), ('example', 'media_player.living_room_sonos')]))])}, 'media_play_pause': {'description': 'Toggle media player play/pause state.', 'fields': OrderedDict([('entity_id', OrderedDict([('description', 'Name(s) of entities to toggle play/pause state on.'), ('example', 'media_player.living_room_sonos')]))])}, 'media_play': {'description': 'Send the media player the command for play.', 'fields': OrderedDict([('entity_id', OrderedDict([('description', 'Name(s) of entities to play on.'), ('example', 'media_player.living_room_sonos')]))])}, 'media_pause': {'description': 'Send the media player the command for pause.', 'fields': OrderedDict([('entity_id', OrderedDict([('description', 'Name(s) of entities to pause on.'), ('example', 'media_player.living_room_sonos')]))])}, 'media_stop': {'description': 'Send the media player the stop command.', 'fields': OrderedDict([('entity_id', OrderedDict([('description', 'Name(s) of entities to stop on.'), ('example', 'media_player.living_room_sonos')]))])}, 'media_next_track': {'description': 'Send the media player the command for next track.', 'fields': OrderedDict([('entity_id', OrderedDict([('description', 'Name(s) of entities to send next track command to.'), ('example', 'media_player.living_room_sonos')]))])}, 'media_previous_track': {'description': 'Send the media player the command for previous track.', 'fields': OrderedDict([('entity_id', OrderedDict([('description', 'Name(s) of entities to send previous track command to.'), ('example', 'media_player.living_room_sonos')]))])}, 'clear_playlist': {'description': 'Send the media player the command to clear players playlist.', 'fields': OrderedDict([('entity_id', OrderedDict([('description', 'Name(s) of entities to change source on.'), ('example', 'media_player.living_room_chromecast')]))])}, 'volume_set': {'description': "Set a media player's volume level.", 'fields': OrderedDict([('entity_id', OrderedDict([('description', 'Name(s) of entities to set volume level on.'), ('example', 'media_player.living_room_sonos')])), ('volume_level', OrderedDict([('description', 'Volume level to set as float.'), ('example', 0.6)]))])}, 'volume_mute': {'description': "Mute a media player's volume.", 'fields': OrderedDict([('entity_id', OrderedDict([('description', 'Name(s) of entities to mute.'), ('example', 'media_player.living_room_sonos')])), ('is_volume_muted', OrderedDict([('description', 'True/false for mute/unmute.'), ('example', True)]))])}, 'media_seek': {'description': 'Send the media player the command to seek in current playing media.', 'fields': OrderedDict([('entity_id', OrderedDict([('description', 'Name(s) of entities to seek media on.'), ('example', 'media_player.living_room_chromecast')])), ('seek_position', OrderedDict([('description', 'Position to seek to. The format is platform dependent.'), ('example', 100)]))])}, 'select_source': {'description': 'Send the media player the command to change input source.', 'fields': OrderedDict([('entity_id', OrderedDict([('description', 'Name(s) of entities to change source on.'), ('example', 'media_player.media_player.txnr535_0009b0d81f82')])), ('source', OrderedDict([('description', 'Name of the source to switch to. Platform dependent.'), ('example', 'video1')]))])}, 'select_sound_mode': {'description': 'Send the media player the command to change sound mode.', 'fields': OrderedDict([('entity_id', OrderedDict([('description', 'Name(s) of entities to change sound mode on.'), ('example', 'media_player.marantz')])), ('sound_mode', OrderedDict([('description', 'Name of the sound mode to switch to.'), ('example', 'Music')]))])}, 'play_media': {'description': 'Send the media player the command for playing media.', 'fields': OrderedDict([('entity_id', OrderedDict([('description', 'Name(s) of entities to seek media on'), ('example', 'media_player.living_room_chromecast')])), ('media_content_id', OrderedDict([('description', 'The ID of the content to play. Platform dependent.'), ('example', 'https://home-assistant.io/images/cast/splash.png')])), ('media_content_type', OrderedDict([('description', 'The type of the content to play. Must be one of music, tvshow, video, episode, channel or playlist'), ('example', 'music')]))])}, 'shuffle_set': {'description': 'Set shuffling state.', 'fields': OrderedDict([('entity_id', OrderedDict([('description', 'Name(s) of entities to set.'), ('example', 'media_player.spotify')])), ('shuffle', OrderedDict([('description', 'True/false for enabling/disabling shuffle.'), ('example', True)]))])}}, 'tts': {'google_say': {'description': '', 'fields': {}}, 'clear_cache': {'description': 'Remove cache files and RAM cache.', 'fields': {}}}, 'hassio': {'addon_start': {'description': '', 'fields': {}}, 'addon_stop': {'description': '', 'fields': {}}, 'addon_restart': {'description': '', 'fields': {}}, 'addon_stdin': {'description': '', 'fields': {}}, 'host_shutdown': {'description': '', 'fields': {}}, 'host_reboot': {'description': '', 'fields': {}}, 'snapshot_full': {'description': '', 'fields': {}}, 'snapshot_partial': {'description': '', 'fields': {}}, 'restore_full': {'description': '', 'fields': {}}, 'restore_partial': {'description': '', 'fields': {}}}}}
2018-07-21 21:30:52 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:52 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:53 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:53 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:53 INFO (MainThread) [homeassistant.components.http.view] Serving /api/hassio/app/chunk.c77b56beea1d4547ff5f.js to 192.168.0.29 (auth: True)
2018-07-21 21:30:53 INFO (MainThread) [homeassistant.components.http.view] Serving /api/hassio/app/chunk.c93f37c558ff32991708.js to 192.168.0.29 (auth: True)
2018-07-21 21:30:53 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:53 INFO (MainThread) [homeassistant.components.http.view] Serving /api/hassio/hassos/info to 192.168.0.29 (auth: True)
2018-07-21 21:30:53 INFO (MainThread) [homeassistant.components.http.view] Serving /api/hassio/supervisor/logs to 192.168.0.29 (auth: True)
2018-07-21 21:30:53 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:53 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:53 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:53 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:53 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Ack)    raw data 0d 02 fd 0a 
2018-07-21 21:30:54 INFO (MainThread) [custom_components.pyvisonic] [handle_msgtype0B] Stop    data is 
2018-07-21 21:30:54 INFO (MainThread) [custom_components.pyvisonic] [handle_msgtype0B] We're in powerlink mode *****************************************
2018-07-21 21:30:54 INFO (MainThread) [custom_components.pyvisonic] [Process Settings] Process Settings from EPROM
2018-07-21 21:30:54 ERROR (MainThread) [homeassistant.core] Error doing job: Exception in callback <bound method SerialTransport._read_ready of SerialTransport(<uvloop.Loop running=True closed=False debug=False>, <custom_components.pyvisonic.VisonicProtocol object at 0x7fab129668>, Serial<id=0x7fab122ef0, open=True>(port='/dev/ttyUSB0', baudrate=9600, bytesize=8, parity='N', stopbits=1, timeout=0, xonxoff=False, rtscts=False, dsrdtr=False))>
Traceback (most recent call last):
  File "uvloop/cbhandles.pyx", line 62, in uvloop.loop.Handle._run
  File "/usr/local/lib/python3.6/site-packages/serial_asyncio/__init__.py", line 106, in _read_ready
    self._protocol.data_received(data)
  File "/config/custom_components/pyvisonic.py", line 860, in data_received
    self.handle_received_byte(databyte)
  File "/config/custom_components/pyvisonic.py", line 941, in handle_received_byte
    self.handle_packet(self.ReceiveData)
  File "/config/custom_components/pyvisonic.py", line 1823, in handle_packet
    self.handle_msgtype0B(packet[2:-2])
  File "/config/custom_components/pyvisonic.py", line 1908, in handle_msgtype0B
    self.ProcessSettings()
  File "/config/custom_components/pyvisonic.py", line 1506, in ProcessSettings
    self.pmPhoneNr_t[i] = self.pmPhoneNr_t[i].append(nr)
AttributeError: 'NoneType' object has no attribute 'append'
2018-07-21 21:30:54 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Exit)    raw data 0d 0f f0 0a 
2018-07-21 21:30:54 INFO (MainThread) [custom_components.pyvisonic] [validatePDU] Not valid packet, CRC failed, may be ongoing and not final 0A
2018-07-21 21:30:54 INFO (MainThread) [custom_components.pyvisonic] [validatePDU] Not valid packet, CRC failed, may be ongoing and not final 0A
2018-07-21 21:30:55 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Setting Time)    raw data 0d 46 f8 00 14 1e 15 15 07 12 ff ff 4b 0a 
2018-07-21 21:30:57 INFO (MainThread) [homeassistant.components.discovery] Unknown service discovered: DLNA {'host': '192.168.0.4', 'port': 50001, 'ssdp_description': 'http://192.168.0.4:50001/desc/device.xml', 'name': 'DS214Play', 'model_name': 'DS214play', 'model_number': None, 'serial': '13A0LTN006132', 'manufacturer': 'Synology Inc', 'udn': 'uuid:00113226-a750-0011-50a7-50a726321100', 'upnp_device_type': 'urn:schemas-upnp-org:device:MediaServer:1'}
2018-07-21 21:30:57 INFO (MainThread) [homeassistant.components.discovery] Unknown service discovered: DLNA {'host': '192.168.0.24', 'port': 8080, 'ssdp_description': 'http://192.168.0.24:8080/MediaRenderer/desc.xml', 'name': 'RX-V773 Mackes', 'model_name': 'RX-V773', 'model_number': 'V773', 'serial': '00C86CE3', 'manufacturer': 'YAMAHA CORPORATION', 'udn': 'uuid:5f9ec1b3-ed59-1900-4530-00a0de95c3b5', 'upnp_device_type': 'urn:schemas-upnp-org:device:MediaRenderer:1'}
2018-07-21 21:30:57 INFO (MainThread) [homeassistant.components.discovery] Unknown service discovered: DLNA {'host': '192.168.0.5', 'port': 49154, 'ssdp_description': 'http://192.168.0.5:49154/MediaRenderer/desc.xml', 'name': 'RX-A1050', 'model_name': 'RX-A1050', 'model_number': 'A1050', 'serial': '0EA47D83', 'manufacturer': 'Yamaha Corporation', 'udn': 'uuid:9ab0c000-f668-11de-9976-00a0ded42c58', 'upnp_device_type': 'urn:schemas-upnp-org:device:MediaRenderer:1'}
2018-07-21 21:30:57 INFO (MainThread) [homeassistant.components.discovery] Found new service: apple_tv {'host': '192.168.0.47', 'port': 3689, 'hostname': 'Apple-TV.local.', 'properties': {'txtvers': '1', 'atSV': '65541', 'hG': '00000000-6478-08eb-4ad6-c1487cdc425c', 'MniT': '167845888', 'fs': '2', 'Name': 'Apple TV', 'PrVs': '65538', 'DFID': '2', 'EiTS': '1', 'MiTPV': '196611'}, 'name': 'Apple TV'}
2018-07-21 21:30:57 INFO (MainThread) [homeassistant.components.discovery] Found new service: samsung_tv {'host': '192.168.0.7', 'port': 52235, 'ssdp_description': 'http://192.168.0.7:52235/rcr/RemoteControlReceiver.xml', 'name': 'UE55D7000', 'model_name': 'UE55D7000', 'model_number': '1.0', 'serial': '20090804RCR', 'manufacturer': 'Samsung Electronics', 'udn': 'uuid:5d6c03f8-4615-b053-00f8-528ec9e1f654', 'upnp_device_type': 'urn:samsung.com:device:RemoteControlReceiver:1'}
2018-07-21 21:30:57 INFO (MainThread) [homeassistant.core] Bus:Handling <Event platform_discovered[L]: service=load_platform.media_player, platform=samsungtv, discovered=host=192.168.0.7, port=52235, ssdp_description=http://192.168.0.7:52235/rcr/RemoteControlReceiver.xml, name=UE55D7000, model_name=UE55D7000, model_number=1.0, serial=20090804RCR, manufacturer=Samsung Electronics, udn=uuid:5d6c03f8-4615-b053-00f8-528ec9e1f654, upnp_device_type=urn:samsung.com:device:RemoteControlReceiver:1>
2018-07-21 21:30:57 INFO (MainThread) [homeassistant.components.discovery] Found new service: yamaha {'host': '192.168.0.5', 'port': 49154, 'ssdp_description': 'http://192.168.0.5:49154/MediaRenderer/desc.xml', 'name': 'RX-A1050', 'model_name': 'RX-A1050', 'model_number': 'A1050', 'serial': '0EA47D83', 'manufacturer': 'Yamaha Corporation', 'udn': 'uuid:9ab0c000-f668-11de-9976-00a0ded42c58', 'upnp_device_type': 'urn:schemas-upnp-org:device:MediaRenderer:1', 'control_url': 'http://192.168.0.5:80/YamahaRemoteControl/ctrl', 'description_url': 'http://192.168.0.5:80/YamahaRemoteControl/desc.xml'}
2018-07-21 21:30:57 INFO (MainThread) [homeassistant.core] Bus:Handling <Event platform_discovered[L]: service=load_platform.media_player, platform=yamaha, discovered=host=192.168.0.5, port=49154, ssdp_description=http://192.168.0.5:49154/MediaRenderer/desc.xml, name=RX-A1050, model_name=RX-A1050, model_number=A1050, serial=0EA47D83, manufacturer=Yamaha Corporation, udn=uuid:9ab0c000-f668-11de-9976-00a0ded42c58, upnp_device_type=urn:schemas-upnp-org:device:MediaRenderer:1, control_url=http://192.168.0.5:80/YamahaRemoteControl/ctrl, description_url=http://192.168.0.5:80/YamahaRemoteControl/desc.xml>
2018-07-21 21:30:57 INFO (MainThread) [homeassistant.components.discovery] Unknown service discovered: spotify_connect {'host': '192.168.0.5', 'port': 80, 'hostname': 'RX-A1050.local.', 'properties': {'VERSION': '1.0', 'CPath': '/goform/spotifyConfig'}}
2018-07-21 21:30:57 INFO (MainThread) [homeassistant.loader] Loaded apple_tv from homeassistant.components.apple_tv
2018-07-21 21:30:57 INFO (MainThread) [homeassistant.loader] Loaded media_player.samsungtv from homeassistant.components.media_player.samsungtv
2018-07-21 21:30:57 INFO (MainThread) [homeassistant.loader] Loaded media_player.yamaha from homeassistant.components.media_player.yamaha
2018-07-21 21:30:59 INFO (MainThread) [homeassistant.core] Bus:Handling <Event config_entry_discovered[L]>
2018-07-21 21:30:59 INFO (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=persistent_notification, service=create, service_data=title=New devices discovered, message=We have discovered new devices on your network. [Check it out](/config/integrations), notification_id=config_entry_discovery, service_call_id=548372961432-3>
2018-07-21 21:30:59 INFO (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=persistent_notification, service=dismiss, service_data=notification_id=config_entry_discovery, service_call_id=548372961432-4>
2018-07-21 21:30:59 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=persistent_notification.config_entry_discovery, old_state=None, new_state=<state persistent_notification.config_entry_discovery=notifying; title=New devices discovered, message=We have discovered new devices on your network. [Check it out](/config/integrations) @ 2018-07-21T21:30:59.964083+00:00>>
2018-07-21 21:30:59 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_executed[L]: service_call_id=548372961432-3>
2018-07-21 21:30:59 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=persistent_notification.config_entry_discovery, old_state=<state persistent_notification.config_entry_discovery=notifying; title=New devices discovered, message=We have discovered new devices on your network. [Check it out](/config/integrations) @ 2018-07-21T21:30:59.964083+00:00>, new_state=None>
2018-07-21 21:30:59 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_executed[L]: service_call_id=548372961432-4>
2018-07-21 21:30:59 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'persistent_notification.config_entry_discovery', 'old_state': None, 'new_state': <state persistent_notification.config_entry_discovery=notifying; title=New devices discovered, message=We have discovered new devices on your network. [Check it out](/config/integrations) @ 2018-07-21T21:30:59.964083+00:00>}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 7, 21, 21, 30, 59, 964258, tzinfo=<UTC>)}}
2018-07-21 21:31:00 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'persistent_notification.config_entry_discovery', 'old_state': <state persistent_notification.config_entry_discovery=notifying; title=New devices discovered, message=We have discovered new devices on your network. [Check it out](/config/integrations) @ 2018-07-21T21:30:59.964083+00:00>, 'new_state': None}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 7, 21, 21, 30, 59, 979270, tzinfo=<UTC>)}}
2018-07-21 21:31:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event config_entry_discovered[L]>
2018-07-21 21:31:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=persistent_notification, service=create, service_data=title=New devices discovered, message=We have discovered new devices on your network. [Check it out](/config/integrations), notification_id=config_entry_discovery, service_call_id=548372961432-5>
2018-07-21 21:31:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=persistent_notification, service=dismiss, service_data=notification_id=config_entry_discovery, service_call_id=548372961432-6>
2018-07-21 21:31:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=persistent_notification.config_entry_discovery, old_state=None, new_state=<state persistent_notification.config_entry_discovery=notifying; title=New devices discovered, message=We have discovered new devices on your network. [Check it out](/config/integrations) @ 2018-07-21T21:31:03.847616+00:00>>
2018-07-21 21:31:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_executed[L]: service_call_id=548372961432-5>
2018-07-21 21:31:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=persistent_notification.config_entry_discovery, old_state=<state persistent_notification.config_entry_discovery=notifying; title=New devices discovered, message=We have discovered new devices on your network. [Check it out](/config/integrations) @ 2018-07-21T21:31:03.847616+00:00>, new_state=None>
2018-07-21 21:31:03 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_executed[L]: service_call_id=548372961432-6>
2018-07-21 21:31:03 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'persistent_notification.config_entry_discovery', 'old_state': None, 'new_state': <state persistent_notification.config_entry_discovery=notifying; title=New devices discovered, message=We have discovered new devices on your network. [Check it out](/config/integrations) @ 2018-07-21T21:31:03.847616+00:00>}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 7, 21, 21, 31, 3, 847783, tzinfo=<UTC>)}}
2018-07-21 21:31:03 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'persistent_notification.config_entry_discovery', 'old_state': <state persistent_notification.config_entry_discovery=notifying; title=New devices discovered, message=We have discovered new devices on your network. [Check it out](/config/integrations) @ 2018-07-21T21:31:03.847616+00:00>, 'new_state': None}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 7, 21, 21, 31, 3, 862090, tzinfo=<UTC>)}}
2018-07-21 21:31:04 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Received {'type': 'config/entity_registry/get', 'entity_id': 'alarm_control_panel.visonic_alarm', 'id': 13}
2018-07-21 21:31:04 INFO (MainThread) [homeassistant.components.http.view] Serving /api/history/period/2018-07-21T21:26:15.272Z to 192.168.0.29 (auth: True)
2018-07-21 21:31:05 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 13, 'type': 'result', 'success': True, 'result': {'entity_id': 'alarm_control_panel.visonic_alarm', 'name': None}}
2018-07-21 21:31:05 DEBUG (SyncWorker_7) [homeassistant.components.recorder.util] converting 1 rows to native objects took 0.137021s
2018-07-21 21:31:05 DEBUG (SyncWorker_7) [homeassistant.components.history] get_significant_states took 0.201095s
2018-07-21 21:31:05 DEBUG (SyncWorker_7) [homeassistant.components.history] getting 0 first datapoints took 0.000036s
2018-07-21 21:31:05 DEBUG (MainThread) [homeassistant.components.history] Extracted 1 states in 0.302750s
2018-07-21 21:31:07 INFO (MainThread) [homeassistant.setup] Setting up apple_tv
2018-07-21 21:31:07 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=apple_tv, service=apple_tv_scan>
2018-07-21 21:31:07 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=apple_tv, service=apple_tv_authenticate>
2018-07-21 21:31:07 INFO (MainThread) [homeassistant.setup] Setup of domain apple_tv took 0.0 seconds.
2018-07-21 21:31:07 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=apple_tv>
2018-07-21 21:31:07 INFO (MainThread) [homeassistant.core] Bus:Handling <Event platform_discovered[L]: service=apple_tv, discovered=host=192.168.0.47, port=3689, hostname=Apple-TV.local., properties=txtvers=1, atSV=65541, hG=00000000-6478-08eb-4ad6-c1487cdc425c, MniT=167845888, fs=2, Name=Apple TV, PrVs=65538, DFID=2, EiTS=1, MiTPV=196611, name=Apple TV>
2018-07-21 21:31:07 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 4, 'type': 'event', 'event': {'event_type': 'service_registered', 'data': {'domain': 'apple_tv', 'service': 'apple_tv_scan'}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 7, 21, 21, 31, 7, 482391, tzinfo=<UTC>)}}
2018-07-21 21:31:07 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 4, 'type': 'event', 'event': {'event_type': 'service_registered', 'data': {'domain': 'apple_tv', 'service': 'apple_tv_authenticate'}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 7, 21, 21, 31, 7, 486390, tzinfo=<UTC>)}}
2018-07-21 21:31:07 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 3, 'type': 'event', 'event': {'event_type': 'component_loaded', 'data': {'component': 'apple_tv'}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 7, 21, 21, 31, 7, 492934, tzinfo=<UTC>)}}
2018-07-21 21:31:08 INFO (MainThread) [homeassistant.core] Bus:Handling <Event platform_discovered[L]: service=load_platform.media_player, platform=apple_tv, discovered=name=Apple TV, host=192.168.0.47, login_id=00000000-6478-08eb-4ad6-c1487cdc425c, start_off=False>
2018-07-21 21:31:08 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Received {'type': 'config/entity_registry/get', 'entity_id': 'switch.visonic_alarm_panel', 'id': 14}
2018-07-21 21:31:08 INFO (MainThread) [homeassistant.components.http.view] Serving /api/history/period/2018-07-21T21:26:06.378Z to 192.168.0.29 (auth: True)
2018-07-21 21:31:09 DEBUG (SyncWorker_1) [homeassistant.components.recorder.util] converting 1 rows to native objects took 0.035197s
2018-07-21 21:31:09 INFO (MainThread) [homeassistant.loader] Loaded remote from homeassistant.components.remote
2018-07-21 21:31:09 DEBUG (SyncWorker_1) [homeassistant.components.history] get_significant_states took 0.065389s
2018-07-21 21:31:09 DEBUG (SyncWorker_1) [homeassistant.components.history] getting 0 first datapoints took 0.000044s
2018-07-21 21:31:09 INFO (MainThread) [homeassistant.loader] Loaded media_player.apple_tv from homeassistant.components.media_player.apple_tv
2018-07-21 21:31:09 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 14, 'type': 'result', 'success': True, 'result': {'entity_id': 'switch.visonic_alarm_panel', 'name': None}}
2018-07-21 21:31:09 DEBUG (MainThread) [homeassistant.components.history] Extracted 1 states in 0.271493s
2018-07-21 21:31:09 INFO (MainThread) [homeassistant.setup] Setting up remote
2018-07-21 21:31:09 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=remote, service=turn_off>
2018-07-21 21:31:09 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=remote, service=turn_on>
2018-07-21 21:31:09 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=remote, service=toggle>
2018-07-21 21:31:09 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=remote, service=send_command>
2018-07-21 21:31:09 INFO (MainThread) [homeassistant.setup] Setup of domain remote took 0.0 seconds.
2018-07-21 21:31:09 INFO (MainThread) [homeassistant.core] Bus:Handling <Event component_loaded[L]: component=remote>
2018-07-21 21:31:09 INFO (MainThread) [homeassistant.components.media_player] Setting up media_player.apple_tv
2018-07-21 21:31:09 INFO (MainThread) [homeassistant.core] Bus:Handling <Event platform_discovered[L]: service=load_platform.remote, platform=apple_tv, discovered=name=Apple TV, host=192.168.0.47, login_id=00000000-6478-08eb-4ad6-c1487cdc425c, start_off=False>
2018-07-21 21:31:09 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 4, 'type': 'event', 'event': {'event_type': 'service_registered', 'data': {'domain': 'remote', 'service': 'turn_off'}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 7, 21, 21, 31, 9, 386169, tzinfo=<UTC>)}}
2018-07-21 21:31:09 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 4, 'type': 'event', 'event': {'event_type': 'service_registered', 'data': {'domain': 'remote', 'service': 'turn_on'}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 7, 21, 21, 31, 9, 390283, tzinfo=<UTC>)}}
2018-07-21 21:31:09 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 4, 'type': 'event', 'event': {'event_type': 'service_registered', 'data': {'domain': 'remote', 'service': 'toggle'}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 7, 21, 21, 31, 9, 396735, tzinfo=<UTC>)}}
2018-07-21 21:31:09 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 4, 'type': 'event', 'event': {'event_type': 'service_registered', 'data': {'domain': 'remote', 'service': 'send_command'}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 7, 21, 21, 31, 9, 402896, tzinfo=<UTC>)}}
2018-07-21 21:31:09 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 3, 'type': 'event', 'event': {'event_type': 'component_loaded', 'data': {'component': 'remote'}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 7, 21, 21, 31, 9, 410624, tzinfo=<UTC>)}}
2018-07-21 21:31:09 INFO (MainThread) [homeassistant.loader] Loaded remote.apple_tv from homeassistant.components.remote.apple_tv
2018-07-21 21:31:09 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=media_player.apple_tv, old_state=None, new_state=<state media_player.apple_tv=unknown; media_title=Establishing a connection to Apple TV..., friendly_name=Apple TV, supported_features=21427 @ 2018-07-21T21:31:09.512036+00:00>>
2018-07-21 21:31:09 DEBUG (MainThread) [pyatv.internal.apple_tv] Waiting for playstatus updates
2018-07-21 21:31:09 DEBUG (MainThread) [pyatv.net] GET URL: http://192.168.0.47:3689/login?hsgid=00000000-6478-08eb-4ad6-c1487cdc425c&hasFP=1
2018-07-21 21:31:09 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'media_player.apple_tv', 'old_state': None, 'new_state': <state media_player.apple_tv=unknown; media_title=Establishing a connection to Apple TV..., friendly_name=Apple TV, supported_features=21427 @ 2018-07-21T21:31:09.512036+00:00>}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 7, 21, 21, 31, 9, 512227, tzinfo=<UTC>)}}
2018-07-21 21:31:09 INFO (MainThread) [homeassistant.components.remote] Setting up remote.apple_tv
2018-07-21 21:31:09 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=remote.apple_tv, old_state=None, new_state=<state remote.apple_tv=on; friendly_name=Apple TV @ 2018-07-21T21:31:09.563174+00:00>>
2018-07-21 21:31:09 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'remote.apple_tv', 'old_state': None, 'new_state': <state remote.apple_tv=on; friendly_name=Apple TV @ 2018-07-21T21:31:09.563174+00:00>}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 7, 21, 21, 31, 9, 563350, tzinfo=<UTC>)}}
2018-07-21 21:31:09 INFO (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=group, service=set, service_data=object_id=all_remotes, name=all remotes, entities=['remote.apple_tv'], visible=False, service_call_id=548372961432-7>
2018-07-21 21:31:09 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=group.all_remotes, old_state=None, new_state=<state group.all_remotes=on; entity_id=('remote.apple_tv',), order=1, auto=True, friendly_name=all remotes, hidden=True @ 2018-07-21T21:31:09.597008+00:00>>
2018-07-21 21:31:09 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'group.all_remotes', 'old_state': None, 'new_state': <state group.all_remotes=on; entity_id=('remote.apple_tv',), order=1, auto=True, friendly_name=all remotes, hidden=True @ 2018-07-21T21:31:09.597008+00:00>}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 7, 21, 21, 31, 9, 597189, tzinfo=<UTC>)}}
2018-07-21 21:31:09 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_executed[L]: service_call_id=548372961432-7>
2018-07-21 21:31:09 INFO (MainThread) [custom_components.pyvisonic] [validatePDU] Not valid packet, CRC failed, may be ongoing and not final 0A
2018-07-21 21:31:09 DEBUG (MainThread) [pyatv.daap] _login_request: mlog: [container, dmap.loginresponse]
  mstt: 200 [uint, dmap.status]
  mlid: 765453451 [uint, dmap.sessionid]

2018-07-21 21:31:09 INFO (MainThread) [pyatv.daap] Logged in and got session id 765453451
2018-07-21 21:31:09 DEBUG (MainThread) [pyatv.net] GET URL: http://192.168.0.47:3689/ctrl-int/1/playstatusupdate?session-id=765453451&revision-number=0
2018-07-21 21:31:09 DEBUG (MainThread) [pyatv.daap] _get_request: cmst: [container, dmcp.playstatus]
  mstt: 200 [uint, dmap.status]
  cmsr: 15 [uint, dmcp.serverrevision]

2018-07-21 21:31:09 DEBUG (MainThread) [pyatv.internal.apple_tv] Waiting for playstatus updates
2018-07-21 21:31:09 DEBUG (MainThread) [pyatv.daap] Already logged in, re-using seasion id 765453451
2018-07-21 21:31:09 DEBUG (MainThread) [pyatv.net] GET URL: http://192.168.0.47:3689/ctrl-int/1/playstatusupdate?session-id=765453451&revision-number=15
2018-07-21 21:31:09 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=media_player.apple_tv, old_state=<state media_player.apple_tv=unknown; media_title=Establishing a connection to Apple TV..., friendly_name=Apple TV, supported_features=21427 @ 2018-07-21T21:31:09.512036+00:00>, new_state=<state media_player.apple_tv=idle; media_duration=0, media_position=0, media_title=Nothing playing, friendly_name=Apple TV, supported_features=21427 @ 2018-07-21T21:31:09.740398+00:00>>
2018-07-21 21:31:09 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'media_player.apple_tv', 'old_state': <state media_player.apple_tv=unknown; media_title=Establishing a connection to Apple TV..., friendly_name=Apple TV, supported_features=21427 @ 2018-07-21T21:31:09.512036+00:00>, 'new_state': <state media_player.apple_tv=idle; media_duration=0, media_position=0, media_title=Nothing playing, friendly_name=Apple TV, supported_features=21427 @ 2018-07-21T21:31:09.740398+00:00>}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 7, 21, 21, 31, 9, 740617, tzinfo=<UTC>)}}
2018-07-21 21:31:14 INFO (MainThread) [homeassistant.components.media_player] Setting up media_player.samsungtv
2018-07-21 21:31:15 INFO (SyncWorker_9) [homeassistant.components.media_player.samsungtv] Samsung TV 192.168.0.7:55000 added as 'UE55D7000 (UE55D7000)'
2018-07-21 21:31:15 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=media_player.ue55d7000_ue55d7000, old_state=None, new_state=<state media_player.ue55d7000_ue55d7000=unknown; is_volume_muted=False, friendly_name=UE55D7000 (UE55D7000), supported_features=18233 @ 2018-07-21T21:31:15.205833+00:00>>
2018-07-21 21:31:15 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'media_player.ue55d7000_ue55d7000', 'old_state': None, 'new_state': <state media_player.ue55d7000_ue55d7000=unknown; is_volume_muted=False, friendly_name=UE55D7000 (UE55D7000), supported_features=18233 @ 2018-07-21T21:31:15.205833+00:00>}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 7, 21, 21, 31, 15, 206005, tzinfo=<UTC>)}}
2018-07-21 21:31:17 INFO (MainThread) [custom_components.pyvisonic] [QueueMessage] I'm Alive Message To Panel
2018-07-21 21:31:17 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (I'm Alive Message To Panel)    raw data 0d ab 03 00 00 00 00 00 00 00 00 00 43 0e 0a 
2018-07-21 21:31:17 INFO (MainThread) [custom_components.pyvisonic] [QueueMessage] Getting Status
2018-07-21 21:31:17 INFO (MainThread) [custom_components.pyvisonic] [validatePDU] Not valid packet, CRC failed, may be ongoing and not final 0A
2018-07-21 21:31:17 INFO (MainThread) [custom_components.pyvisonic] [validatePDU] Not valid packet, CRC failed, may be ongoing and not final 0A
2018-07-21 21:31:18 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Received {'type': 'config/entity_registry/get', 'entity_id': 'switch.visonic_alarm_panel', 'id': 15}
2018-07-21 21:31:18 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 15, 'type': 'result', 'success': True, 'result': {'entity_id': 'switch.visonic_alarm_panel', 'name': None}}
2018-07-21 21:31:18 INFO (MainThread) [homeassistant.components.http.view] Serving /api/history/period/2018-07-21T21:31:07.952Z to 192.168.0.29 (auth: True)
2018-07-21 21:31:18 DEBUG (SyncWorker_19) [homeassistant.components.recorder.util] converting 0 rows to native objects took 0.024032s
2018-07-21 21:31:18 DEBUG (SyncWorker_19) [homeassistant.components.history] get_significant_states took 0.054609s
2018-07-21 21:31:18 DEBUG (SyncWorker_19) [homeassistant.components.history] getting 0 first datapoints took 0.000039s
2018-07-21 21:31:18 INFO (MainThread) [homeassistant.components.media_player] Setting up media_player.yamaha
2018-07-21 21:31:18 DEBUG (MainThread) [homeassistant.components.history] Extracted 0 states in 0.070016s
2018-07-21 21:31:18 DEBUG (SyncWorker_18) [homeassistant.components.media_player.yamaha] Receivers: [<RXV model_name="RX-A1050" zone="Main_Zone" ctrl_url="http://192.168.0.5:80/YamahaRemoteControl/ctrl" at 0x7fa6803710>, <RXV model_name="RX-A1050" zone="Zone_2" ctrl_url="http://192.168.0.5:80/YamahaRemoteControl/ctrl" at 0x7fa68030b8>]
2018-07-21 21:31:18 INFO (MainThread) [homeassistant.core] Bus:Handling <Event service_registered[L]: domain=media_player, service=yamaha_enable_output>
2018-07-21 21:31:18 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 4, 'type': 'event', 'event': {'event_type': 'service_registered', 'data': {'domain': 'media_player', 'service': 'yamaha_enable_output'}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 7, 21, 21, 31, 18, 672086, tzinfo=<UTC>)}}
2018-07-21 21:31:18 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=media_player.rxa1050_zone_2, old_state=None, new_state=<state media_player.rxa1050_zone_2=off; friendly_name=RX-A1050 Zone 2, supported_features=18828 @ 2018-07-21T21:31:18.690232+00:00>>
2018-07-21 21:31:18 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=media_player.rxa1050, old_state=None, new_state=<state media_player.rxa1050=off; friendly_name=RX-A1050, supported_features=18828 @ 2018-07-21T21:31:18.699918+00:00>>
2018-07-21 21:31:18 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'media_player.rxa1050_zone_2', 'old_state': None, 'new_state': <state media_player.rxa1050_zone_2=off; friendly_name=RX-A1050 Zone 2, supported_features=18828 @ 2018-07-21T21:31:18.690232+00:00>}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 7, 21, 21, 31, 18, 690471, tzinfo=<UTC>)}}
2018-07-21 21:31:18 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'media_player.rxa1050', 'old_state': None, 'new_state': <state media_player.rxa1050=off; friendly_name=RX-A1050, supported_features=18828 @ 2018-07-21T21:31:18.699918+00:00>}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 7, 21, 21, 31, 18, 700082, tzinfo=<UTC>)}}
2018-07-21 21:31:18 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Getting Status)    raw data 0d a2 00 00 00 00 00 00 00 00 00 00 43 1a 0a 
2018-07-21 21:31:18 INFO (MainThread) [custom_components.pyvisonic] [validatePDU] Not valid packet, CRC failed, may be ongoing and not final 0A
2018-07-21 21:31:18 INFO (MainThread) [custom_components.pyvisonic] [validatePDU] Not valid packet, CRC failed, may be ongoing and not final 0A
2018-07-21 21:31:26 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=media_player.ue55d7000_ue55d7000, old_state=<state media_player.ue55d7000_ue55d7000=unknown; is_volume_muted=False, friendly_name=UE55D7000 (UE55D7000), supported_features=18233 @ 2018-07-21T21:31:15.205833+00:00>, new_state=<state media_player.ue55d7000_ue55d7000=on; is_volume_muted=False, friendly_name=UE55D7000 (UE55D7000), supported_features=18233 @ 2018-07-21T21:31:26.242652+00:00>>
2018-07-21 21:31:26 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'media_player.ue55d7000_ue55d7000', 'old_state': <state media_player.ue55d7000_ue55d7000=unknown; is_volume_muted=False, friendly_name=UE55D7000 (UE55D7000), supported_features=18233 @ 2018-07-21T21:31:15.205833+00:00>, 'new_state': <state media_player.ue55d7000_ue55d7000=on; is_volume_muted=False, friendly_name=UE55D7000 (UE55D7000), supported_features=18233 @ 2018-07-21T21:31:26.242652+00:00>}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 7, 21, 21, 31, 26, 242971, tzinfo=<UTC>)}}
2018-07-21 21:31:28 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Received {'type': 'config/entity_registry/get', 'entity_id': 'switch.visonic_alarm_panel', 'id': 16}
2018-07-21 21:31:28 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 16, 'type': 'result', 'success': True, 'result': {'entity_id': 'switch.visonic_alarm_panel', 'name': None}}
2018-07-21 21:31:28 INFO (MainThread) [homeassistant.components.http.view] Serving /api/history/period/2018-07-21T21:31:17.408Z to 192.168.0.29 (auth: True)
2018-07-21 21:31:28 DEBUG (SyncWorker_0) [homeassistant.components.recorder.util] converting 0 rows to native objects took 0.023786s
2018-07-21 21:31:28 DEBUG (SyncWorker_0) [homeassistant.components.history] get_significant_states took 0.043879s
2018-07-21 21:31:28 DEBUG (SyncWorker_0) [homeassistant.components.history] getting 0 first datapoints took 0.000036s
2018-07-21 21:31:28 DEBUG (MainThread) [homeassistant.components.history] Extracted 0 states in 0.053014s
2018-07-21 21:31:29 INFO (MainThread) [custom_components.pyvisonic] [SendCommand] Re-Sending last message  Getting Status
2018-07-21 21:31:29 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Getting Status)    raw data 0d a2 00 00 00 00 00 00 00 00 00 00 43 1a 0a 
2018-07-21 21:31:29 INFO (MainThread) [custom_components.pyvisonic] [validatePDU] Not valid packet, CRC failed, may be ongoing and not final 0A
2018-07-21 21:31:29 INFO (MainThread) [custom_components.pyvisonic] [validatePDU] Not valid packet, CRC failed, may be ongoing and not final 0A
2018-07-21 21:31:29 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=media_player.rxa1050, old_state=<state media_player.rxa1050=off; friendly_name=RX-A1050, supported_features=18828 @ 2018-07-21T21:31:18.699918+00:00>, new_state=<state media_player.rxa1050=on; volume_level=0.37, is_volume_muted=False, source=AV1, source_list=['AUDIO1', 'AUDIO2', 'AUDIO3', 'AV1', 'AV2', 'AV3', 'AV4', 'AV5', 'AV6', 'AV7', 'AirPlay', 'Bluetooth', 'JUKE', 'MusicCast Link', 'NET RADIO', 'Napster', 'PHONO', 'SERVER', 'Spotify', 'TUNER', 'USB', 'V-AUX', 'iPod (USB)'], friendly_name=RX-A1050, supported_features=18828 @ 2018-07-21T21:31:29.855766+00:00>>
2018-07-21 21:31:29 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'media_player.rxa1050', 'old_state': <state media_player.rxa1050=off; friendly_name=RX-A1050, supported_features=18828 @ 2018-07-21T21:31:18.699918+00:00>, 'new_state': <state media_player.rxa1050=on; volume_level=0.37, is_volume_muted=False, source=AV1, source_list=['AUDIO1', 'AUDIO2', 'AUDIO3', 'AV1', 'AV2', 'AV3', 'AV4', 'AV5', 'AV6', 'AV7', 'AirPlay', 'Bluetooth', 'JUKE', 'MusicCast Link', 'NET RADIO', 'Napster', 'PHONO', 'SERVER', 'Spotify', 'TUNER', 'USB', 'V-AUX', 'iPod (USB)'], friendly_name=RX-A1050, supported_features=18828 @ 2018-07-21T21:31:29.855766+00:00>}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 7, 21, 21, 31, 29, 855963, tzinfo=<UTC>)}}
2018-07-21 21:31:30 INFO (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sun.sun, old_state=<state sun.sun=below_horizon; next_dawn=2018-07-22T05:40:51+00:00, next_dusk=2018-07-22T18:32:03+00:00, next_midnight=2018-07-22T00:06:28+00:00, next_noon=2018-07-22T12:06:27+00:00, next_rising=2018-07-22T06:02:54+00:00, next_setting=2018-07-22T18:10:00+00:00, elevation=-46.75, azimuth=300.21, friendly_name=Sun @ 2018-07-21T21:30:03.875818+00:00>, new_state=<state sun.sun=below_horizon; next_dawn=2018-07-22T05:40:51+00:00, next_dusk=2018-07-22T18:32:03+00:00, next_midnight=2018-07-22T00:06:28+00:00, next_noon=2018-07-22T12:06:27+00:00, next_rising=2018-07-22T06:02:54+00:00, next_setting=2018-07-22T18:10:00+00:00, elevation=-47.06, azimuth=300.4, friendly_name=Sun @ 2018-07-21T21:30:03.875818+00:00>>
2018-07-21 21:31:30 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'sun.sun', 'old_state': <state sun.sun=below_horizon; next_dawn=2018-07-22T05:40:51+00:00, next_dusk=2018-07-22T18:32:03+00:00, next_midnight=2018-07-22T00:06:28+00:00, next_noon=2018-07-22T12:06:27+00:00, next_rising=2018-07-22T06:02:54+00:00, next_setting=2018-07-22T18:10:00+00:00, elevation=-46.75, azimuth=300.21, friendly_name=Sun @ 2018-07-21T21:30:03.875818+00:00>, 'new_state': <state sun.sun=below_horizon; next_dawn=2018-07-22T05:40:51+00:00, next_dusk=2018-07-22T18:32:03+00:00, next_midnight=2018-07-22T00:06:28+00:00, next_noon=2018-07-22T12:06:27+00:00, next_rising=2018-07-22T06:02:54+00:00, next_setting=2018-07-22T18:10:00+00:00, elevation=-47.06, azimuth=300.4, friendly_name=Sun @ 2018-07-21T21:30:03.875818+00:00>}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 7, 21, 21, 31, 30, 72570, tzinfo=<UTC>)}}
2018-07-21 21:31:36 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Received {'type': 'config/entity_registry/get', 'entity_id': 'alarm_control_panel.visonic_alarm', 'id': 17}
2018-07-21 21:31:36 DEBUG (MainThread) [homeassistant.components.websocket_api] WS 548282204736: Sending {'id': 17, 'type': 'result', 'success': True, 'result': {'entity_id': 'alarm_control_panel.visonic_alarm', 'name': None}}
2018-07-21 21:31:36 INFO (MainThread) [homeassistant.components.http.view] Serving /api/history/period/2018-07-21T21:31:03.980Z to 192.168.0.29 (auth: True)
2018-07-21 21:31:36 DEBUG (SyncWorker_13) [homeassistant.components.recorder.util] converting 0 rows to native objects took 0.024324s
2018-07-21 21:31:36 DEBUG (SyncWorker_13) [homeassistant.components.history] get_significant_states took 0.045369s
2018-07-21 21:31:36 DEBUG (SyncWorker_13) [homeassistant.components.history] getting 0 first datapoints took 0.000039s
2018-07-21 21:31:36 DEBUG (MainThread) [homeassistant.components.history] Extracted 0 states in 0.054996s
2018-07-21 21:31:39 INFO (MainThread) [custom_components.pyvisonic] [validatePDU] Not valid packet, CRC failed, may be ongoing and not final 0A
2018-07-21 21:31:40 INFO (MainThread) [custom_components.pyvisonic] [QueueMessage] I'm Alive Message To Panel
2018-07-21 21:31:40 INFO (MainThread) [custom_components.pyvisonic] [SendCommand] Tried Re-Sending last message but didn't work. Assume a powerlink timeout state and reset
2018-07-21 21:31:40 INFO (MainThread) [custom_components.pyvisonic] [QueueMessage] Exit
2018-07-21 21:31:40 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Exit)    raw data 0d 0f f0 0a 
2018-07-21 21:31:40 INFO (MainThread) [custom_components.pyvisonic] [QueueMessage] Restore PowerMax/Master Connection
2018-07-21 21:31:40 INFO (MainThread) [custom_components.pyvisonic] [validatePDU] Not valid packet, CRC failed, may be ongoing and not final 0A
2018-07-21 21:31:41 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Restore PowerMax/Master Connection)    raw data 0d ab 06 00 00 00 00 00 00 00 00 00 43 0b 0a 
2018-07-21 21:31:41 INFO (MainThread) [custom_components.pyvisonic] [validatePDU] Not valid packet, CRC failed, may be ongoing and not final 0A
2018-07-21 21:31:41 INFO (MainThread) [custom_components.pyvisonic] [validatePDU] Not valid packet, CRC failed, may be ongoing and not final 0A
2018-07-21 21:31:52 INFO (MainThread) [custom_components.pyvisonic] [SendCommand] Re-Sending last message  Restore PowerMax/Master Connection
2018-07-21 21:31:52 INFO (MainThread) [custom_components.pyvisonic] [pmSendPdu] Sending Command (Restore PowerMax/Master Connection)    raw data 0d ab 06 00 00 00 00 00 00 00 00 00 43 0b 0a 
2018-07-21 21:31:52 INFO (MainThread) [custom_components.pyvisonic] [validatePDU] Not valid packet, CRC failed, may be ongoing and not final 0A
2018-07-21 21:31:52 INFO (MainThread) [custom_components.pyvisonic] [validatePDU] Not valid packet, CRC failed, may be ongoing and not final 0A
