Ok,
Its got to be gremlins and goblins, as this was not firing last night, but its working perfectly fine today. Odd stuff indeed.
2018-11-01 14:46:23 DEBUG (MainThread) [elkm1_lib.elk] got_data '0AZC159B00B1'
2018-11-01 14:46:23 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event state_changed[L]: entity_id=sensor.zone159, old_state=<state sensor.zone159=Normal; index=159, physical_status=open, logical_status=normal, definition=non_alarm, area=1, bypassed=False, triggered_alarm=False, friendly_name=BTN - Doorbell B, icon=mdi:alarm-off @ 2018-10-31T23:37:45.392034+00:00>, new_state=<state sensor.zone159=Violated; index=159, physical_status=short, logical_status=violated, definition=non_alarm, area=1, bypassed=False, triggered_alarm=False, friendly_name=BTN - Doorbell B, icon=mdi:alarm-off @ 2018-11-01T14:46:23.683362+00:00>>
2018-11-01 14:46:23 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection.140403800261800] Sending {'id': 35, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'sensor.zone159', 'old_state': <state sensor.zone159=Normal; index=159, physical_status=open, logical_status=normal, definition=non_alarm, area=1, bypassed=False, triggered_alarm=False, friendly_name=BTN - Doorbell B, icon=mdi:alarm-off @ 2018-10-31T23:37:45.392034+00:00>, 'new_state': <state sensor.zone159=Violated; index=159, physical_status=short, logical_status=violated, definition=non_alarm, area=1, bypassed=False, triggered_alarm=False, friendly_name=BTN - Doorbell B, icon=mdi:alarm-off @ 2018-11-01T14:46:23.683362+00:00>}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 11, 1, 14, 46, 23, 683429, tzinfo=<UTC>), 'context': {'id': '39de1abbd0b64e31b3c08f061ecd3b3f', 'user_id': None}}}
2018-11-01 14:46:23 INFO (MainThread) [homeassistant.components.automation] Executing Notify Backdoor Visitor
2018-11-01 14:46:23 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event logbook_entry[L]: name=Notify Backdoor Visitor, message=has been triggered, domain=automation, entity_id=automation.notify_backdoor_visitor>
2018-11-01 14:46:23 INFO (MainThread) [homeassistant.helpers.script] Script Notify Backdoor Visitor: Running script
2018-11-01 14:46:23 INFO (MainThread) [homeassistant.helpers.script] Script Notify Backdoor Visitor: Executing step call service
2018-11-01 14:46:23 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=script, service=notify, service_data=tell=everyone, message=there appears to be guests at the backdoor, image=http://x.x.x.x/ISAPI/Streaming/channels/101/picture, service_call_id=9cf0b5138e91482ea00749b51ed2ee17>
2018-11-01 14:46:23 DEBUG (MainThread) [homeassistant.components.websocket_api.http.connection.140403806227424] Sending {'id': 2, 'type': 'event', 'event': {'event_type': 'state_changed', 'data': {'entity_id': 'sensor.zone159', 'old_state': <state sensor.zone159=Normal; index=159, physical_status=open, logical_status=normal, definition=non_alarm, area=1, bypassed=False, triggered_alarm=False, friendly_name=BTN - Doorbell B, icon=mdi:alarm-off @ 2018-10-31T23:37:45.392034+00:00>, 'new_state': <state sensor.zone159=Violated; index=159, physical_status=short, logical_status=violated, definition=non_alarm, area=1, bypassed=False, triggered_alarm=False, friendly_name=BTN - Doorbell B, icon=mdi:alarm-off @ 2018-11-01T14:46:23.683362+00:00>}, 'origin': 'LOCAL', 'time_fired': datetime.datetime(2018, 11, 1, 14, 46, 23, 683429, tzinfo=<UTC>), 'context': {'id': '39de1abbd0b64e31b3c08f061ecd3b3f', 'user_id': None}}}
2018-11-01 14:46:23 INFO (MainThread) [homeassistant.helpers.script] Script notify: Running script
2018-11-01 14:46:23 INFO (MainThread) [homeassistant.helpers.script] Script notify: Executing step call service
2018-11-01 14:46:23 DEBUG (MainThread) [homeassistant.core] Bus:Handling <Event call_service[L]: domain=script, service=message, service_data=no_show=, no_say=, who=notify.debug, media_player=media_player.sonos_kitchen, image=http://[email protected]/ISAPI/Streaming/channels/101/picture, message=You asked me to inform you when there appears to be guests at the backdoorI have an intersting fact for you
I have no explanation, ill monitor it more close, it might have to do with system reboots. and I see 0.81.2 is ready to download, so ill do that and see how long it takes for the system to come back up with full debug enabled, and automation to fire (intel NUC so should be cool)
/d