Rfxtrx 433 door bell slow to trigger automation, or not at all

I’ve just set up my rf 433 doorbell with my rfxcom rfxtrx 433, through a mac. I have it configured to announce a button press over Google Home. While it works most of the time (now that I have added fire_event as it always submits the same state), it sometimes (1/4) doesn’t result in the automation triggering at all, and when it does work it normally takes 15 ish seconds for the announcement. The logs look as follows but I can’t see anything in the RFXtrx log lines to link a line to the door bell. Also can’t see any consistency in device_id before each successful automation trigger.
I’ve included the various configs after the log messages:

Log

2020-04-03 20:44:19 DEBUG (Thread-13) [homeassistant.components.rfxtrx] Receive RFXCOM event from (Device_id: 4c_04 Class: RFXtrxDevice Sub: 2, Pkt_id: 085002fb4c0400d579)
2020-04-03 20:44:20 DEBUG (Thread-13) [homeassistant.components.rfxtrx] Receive RFXCOM event from (Device_id: 46_01 Class: RFXtrxDevice Sub: 2, Pkt_id: 085002fc460100c779)
2020-04-03 20:44:23 DEBUG (Thread-13) [homeassistant.components.rfxtrx] Receive RFXCOM event from (Device_id: bd_02 Class: RFXtrxDevice Sub: 2, Pkt_id: 085002fdbd0200cd79)
2020-04-03 20:44:40 DEBUG (Thread-13) [homeassistant.components.rfxtrx] Receive RFXCOM event from (Device_id: e2_01 Class: RFXtrxDevice Sub: 2, Pkt_id: 085002fee20100d359)
2020-04-03 20:44:59 DEBUG (Thread-13) [homeassistant.components.rfxtrx] Receive RFXCOM event from (Device_id: 46_01 Class: RFXtrxDevice Sub: 2, Pkt_id: 085002ff460100c769)
2020-04-03 20:45:02 DEBUG (Thread-13) [homeassistant.components.rfxtrx] Receive RFXCOM event from (Device_id: 4c_04 Class: RFXtrxDevice Sub: 2, Pkt_id: 085002004c0400d579)
2020-04-03 20:45:04 DEBUG (Thread-13) [homeassistant.components.rfxtrx] Receive RFXCOM event from (Device_id: bd_02 Class: RFXtrxDevice Sub: 2, Pkt_id: 08500201bd0200cd79)
2020-04-03 20:45:19 DEBUG (Thread-13) [homeassistant.components.rfxtrx] Receive RFXCOM event from (Device_id: e2_01 Class: RFXtrxDevice Sub: 2, Pkt_id: 08500202e20100d359)
2020-04-03 20:45:38 DEBUG (Thread-13) [homeassistant.components.rfxtrx] Receive RFXCOM event from (Device_id: 46_01 Class: RFXtrxDevice Sub: 2, Pkt_id: 08500203460100c769)
2020-04-03 20:45:42 DEBUG (Thread-13) [homeassistant.components.rfxtrx] Receive RFXCOM event from (Device_id: 00_2e Class: RFXtrxDevice Sub: 2, Pkt_id: 07160204002e3880)
2020-04-03 20:45:45 DEBUG (Thread-13) [homeassistant.components.rfxtrx] Receive RFXCOM event from (Device_id: bd_02 Class: RFXtrxDevice Sub: 2, Pkt_id: 08500205bd0200cd79)
2020-04-03 20:45:58 DEBUG (Thread-13) [homeassistant.components.rfxtrx] Receive RFXCOM event from (Device_id: e2_01 Class: RFXtrxDevice Sub: 2, Pkt_id: 08500206e20100d369)
2020-04-03 20:46:04 INFO (MainThread) [homeassistant.components.automation] Executing Front Door Bell Rings
2020-04-03 20:46:04 INFO (MainThread) [homeassistant.components.automation] Front Door Bell Rings: Running script
2020-04-03 20:46:04 INFO (MainThread) [homeassistant.components.automation] Front Door Bell Rings: Executing step call service
2020-04-03 20:46:04 INFO (Thread-6) [pychromecast.controllers] Receiver:Launching app CC1AD845

logging:

logger:
  default: info
  logs:
    homeassistant.components.rfxtrx: debug

rfxtrx:

sensor:
  - platform: rfxtrx
    devices:
      0716029e002e3870:
        name: "Front Door Bell"
        fire_event: true

automation:

- id: '1585919167297'
  alias: "Front Door Bell Rings"
  description: "Google Home speaks when front door rings"
  trigger:
    platform: event
    event_type: state_changed
    event_data:
      entity_id: sensor.front_door_bell_sound
  condition: []
  action:
  - data:
      entity_id: media_player.all_speakers
      message: "Ding dong. Someone is at the front door"
    service: tts.google_translate_say

Is this just normal behaviour for 433 integration? Is there anything wrong in my setup? Any more debugging I can try?

Thanks
Sorry if this post isn’t in the right section - I wasn’t clear where the best place would be.

This may be normal. Is this measured time or guess?
I send html5 notification through Google and take about 5-10 seconds after automation trigger. Alexa announcement about 4 seconds after in comparison. Point is, this connection not happen on local network so delay is normal but 15 does sound long.

Automation not fire is likely other cause than ha.
Have you confirmed…button press>>ha receive press>>no automation trigger?

Thanks for the reply.

I can’t work out what log message corresponds to the rfxtrx capturing the door bell, so I cannot tell whether it gets the signal in the case that no message is ever spoken. It just took over 30 seconds for the 1 test I did today, from pressing the button to hearing Google speak.

I should have mentioned in my original post, if I change the state of the doorbell through the Home Assistant UI Developer Tools states tab, Google speaks instantly ever time, so I do not believe the issue is with the Google integration.

I was hoping someone would be able to let me know how to tell for sure when the rfxtrx gets the doorbell signal so I can tell where the delay is at least, or better yet that there is a simple fix :slight_smile:

Push button, wait for message, post log for that time period if possible

That’s what I did above. Is there some additional logging I should enable to help please?

When the message isn’t spoken, none of the INFO messages about the automation appear in the logs