Door OPENS when CLOSED COMMAND is sent after power loss to opener | Security+ 1.0 | ESPHome | 2.5i Board on 2024.10.3 Firmware

[tmason818]

I have the wall mount opener RJ020 that lives in the weird in-between space of having a round yellow Learn button but being Security+ 1.0 device.

After a recent and very brief 30 second mid-day power outage I noticed that the RATGDO opened my Garage Door in the middle of the night when one of my failsafe Home Assistant automations sent the Close command.

I've recreated this by doing the following

1. Remove power from the Garage Door Opener for approximately 30 seconds. (Optionally remove the RATGDO from power)
2. Reconnect power and wait for the Garage Door to fully activate the Wall Button (If the RATGDO was removed from power reconnect it to power and wait for it to start up and connect to Wi-Fi)
3. Send a Close command via the RATGDO
4. The RATGDO will Open the Garage Door

Here is what the logbook looks like in Home Assistant for the above- you can see that the door status is correctly showing as Closed and when the Close action is sent it Opens:

Thereafter sending the Close command to an already closed door does nothing- as expected.

[tmason818]

Just a quick update: I did an OTA update to 2024.12.4 and this bug where the garage opens when the close command is sent after a power outage can still be recreated by following the steps in the first post.

Home Assistant Logs:

...my Garage Door did not open in two seconds. But if someone knows one that can I could be interested 😅

[PaulWieland]

Need the log. The home assistant events aren’t helpful in this situation.

Secplus1 does not have discrete serial commands for open and close, it’s only a toggle. The firmware adds discrete controls by comparing the requested command with the current door state and only sends the toggle command if the door state is what is expected.

My guess is something is sending a command before the secplus1 startup sync finishes so ratgdo doesn’t know the actual door state yet.

[tmason818]

My guess is something is sending a command before the secplus1 startup sync finishes so ratgdo doesn’t know the actual door state yet.

I was thinking that too. I was able to get some logs directly from the RATGDO this time.

I changed my testing regimen slightly:

1. Left RATGDO powered on
2. Powered off the Garage Door Opener for approx 5 mins so the wall panel would completely power down
3. Powered the Garage Door Opener back up and watched these logs:

Time	level	Tag	Message
16:18:33	[D]	[ratgdo_secplus1:299]	
wall panel is starting
16:18:33	[D]	[ratgdo_secplus1:299]	
wall panel is starting
16:18:34	[D]	[ratgdo_secplus1:299]	
wall panel is starting
16:18:34	[D]	[ratgdo_secplus1:299]	
wall panel is starting
16:18:34	[D]	[ratgdo_secplus1:299]	
wall panel is starting
16:18:34	[D]	[ratgdo_secplus1:299]	
wall panel is starting
16:18:34	[D]	[ratgdo_secplus1:299]	
wall panel is starting
16:18:34	[D]	[ratgdo_secplus1:299]	
wall panel is starting
16:18:34	[D]	[ratgdo_secplus1:299]	
wall panel is starting
16:18:34	[D]	[ratgdo_secplus1:299]	
wall panel is starting
16:18:34	[D]	[ratgdo_secplus1:299]	
wall panel is starting
16:18:34	[D]	[ratgdo_secplus1:299]	
wall panel is starting
16:18:34	[D]	[ratgdo_secplus1:299]	
wall panel is starting
16:18:35	[D]	[ratgdo_secplus1:299]	
wall panel is starting
16:18:35	[D]	[ratgdo_secplus1:299]	
wall panel is starting
16:18:35	[D]	[ratgdo_secplus1:299]	
wall panel is starting
16:18:35	[D]	[ratgdo_secplus1:299]	
wall panel is starting
16:18:35	[D]	[ratgdo_secplus1:299]	
wall panel is starting
16:18:35	[D]	[ratgdo_secplus1:299]	
wall panel is starting
16:18:35	[D]	[ratgdo_secplus1:299]	
wall panel is starting
16:18:35	[D]	[ratgdo_secplus1:299]	
wall panel is starting
16:18:35	[D]	[ratgdo_secplus1:299]	
wall panel is starting
16:18:35	[D]	[ratgdo_secplus1:299]	
wall panel is starting
16:18:36	[D]	[ratgdo_secplus1:299]	
wall panel is starting
16:18:36	[D]	[ratgdo_secplus1:299]	
wall panel is starting
16:18:36	[D]	[ratgdo_secplus1:299]	
wall panel is starting
16:18:37	[D]	[ratgdo_secplus1:299]	
wall panel is starting
16:18:37	[D]	[ratgdo_secplus1:299]	
wall panel is starting
16:18:38	[D]	[ratgdo:222]	
Light state=ON
16:18:38	[D]	[ratgdo:228]	
Lock state=UNLOCKED
16:18:38	[D]	[ratgdo:222]	
Light state=ON
16:18:38	[D]	[ratgdo:228]	
Lock state=UNLOCKED
16:18:38	[D]	[ratgdo:222]	
Light state=ON
16:18:38	[D]	[ratgdo:228]	
Lock state=UNLOCKED
16:18:39	[D]	[ratgdo:222]	
Light state=ON
16:18:39	[D]	[ratgdo:228]	
Lock state=UNLOCKED
16:18:39	[D]	[ratgdo:113]	
Door state=STOPPED
16:18:39	[D]	[cover:170]	
'Door' - Publishing:
16:18:39	[D]	[cover:173]	
  Position: 0%
16:18:39	[D]	[cover:186]	
  Current Operation: IDLE
16:18:39	[D]	[switch:016]	
'Status door' Turning OFF.
16:18:39	[D]	[ratgdo:222]	
Light state=ON
16:18:39	[D]	[ratgdo:228]	
Lock state=UNLOCKED
16:18:40	[D]	[ratgdo:222]	
Light state=ON
16:18:40	[D]	[ratgdo:228]	
Lock state=UNLOCKED
16:18:40	[D]	[ratgdo:113]	
Door state=STOPPED
16:18:40	[D]	[ratgdo:222]	
Light state=ON
16:18:40	[D]	[ratgdo:228]	
Lock state=UNLOCKED
16:18:41	[D]	[ratgdo:222]	
Light state=ON
16:18:41	[D]	[ratgdo:228]	
Lock state=UNLOCKED

Once the Current Operation: IDLE, messages appeared and the wall panel beeped I waited approx 1 min and sent a Close command and the garage Opened. I got these logs:

Time	level	Tag	Message
16:19:17	[D]	[ratgdo:113]	
Door state=STOPPED
16:19:17	[D]	[ratgdo:222]	
Light state=ON
16:19:17	[D]	[ratgdo:228]	
Lock state=UNLOCKED
16:19:18	[D]	[ratgdo:222]	
Light state=ON
16:19:18	[D]	[ratgdo:228]	
Lock state=UNLOCKED
16:19:18	[D]	[ratgdo:113]	
Door state=STOPPED
16:19:18	[D]	[ratgdo:222]	
Light state=ON
16:19:18	[D]	[ratgdo:228]	
Lock state=UNLOCKED
16:19:19	[D]	[ratgdo:222]	
Light state=ON
16:19:19	[D]	[ratgdo:228]	
Lock state=UNLOCKED
16:19:19	[D]	[cover:076]	
'Door' - Setting
16:19:19	[D]	[cover:084]	
  Position: 0%
16:19:19	[W]	[ratgdo_secplus1:264]	
[2356543] Discard incomplete packet: [38 ...]
16:19:20	[D]	[ratgdo:222]	
Light state=ON
16:19:20	[D]	[ratgdo:228]	
Lock state=UNLOCKED
16:19:20	[D]	[ratgdo:222]	
Light state=ON
16:19:21	[D]	[ratgdo:228]	
Lock state=UNLOCKED
16:19:21	[D]	[ratgdo:222]	
Light state=ON
16:19:21	[D]	[ratgdo:228]	
Lock state=UNLOCKED
16:19:21	[D]	[ratgdo:113]	
Door state=OPENING
16:19:21	[D]	[cover:170]	
'Door' - Publishing:
16:19:21	[D]	[cover:173]	
  Position: 0%
16:19:21	[D]	[cover:186]	
  Current Operation: OPENING
16:19:22	[D]	[ratgdo:222]	
Light state=ON
16:19:22	[D]	[ratgdo:228]	
Lock state=UNLOCKED
16:19:22	[D]	[cover:170]	
'Door' - Publishing:
16:19:22	[D]	[cover:173]	
  Position: 4%
16:19:22	[D]	[cover:186]	
  Current Operation: OPENING
16:19:22	[D]	[ratgdo:222]	
Light state=ON
16:19:22	[D]	[ratgdo:228]	
Lock state=UNLOCKED
16:19:22	[D]	[ratgdo:113]	
Door state=OPENING
16:19:22	[D]	[cover:170]	
'Door' - Publishing:
16:19:22	[D]	[cover:173]	
  Position: 7%
16:19:22	[D]	[cover:186]	
  Current Operation: OPENING
16:19:22	[D]	[ratgdo:222]	
Light state=ON
16:19:23	[D]	[ratgdo:228]	
Lock state=UNLOCKED
16:19:23	[D]	[cover:170]	
'Door' - Publishing:
16:19:23	[D]	[cover:173]	
  Position: 11%
16:19:23	[D]	[cover:186]	
  Current Operation: OPENING
16:19:23	[D]	[ratgdo:222]	
Light state=ON
16:19:23	[D]	[ratgdo:228]	
Lock state=UNLOCKED
16:19:23	[D]	[ratgdo:113]	
Door state=OPENING
16:19:23	[D]	[cover:170]	
'Door' - Publishing:
16:19:23	[D]	[cover:173]	
  Position: 14%
16:19:23	[D]	[cover:186]	
  Current Operation: OPENING
16:19:23	[D]	[ratgdo:222]	
Light state=ON
16:19:24	[D]	[ratgdo:228]	
Lock state=UNLOCKED
16:19:24	[D]	[cover:170]	
'Door' - Publishing:
16:19:24	[D]	[cover:173]	
  Position: 18%
16:19:24	[D]	[cover:186]	
  Current Operation: OPENING

Just to make sure I did steps 1-3 again and then waited for 10 mins to make sure the everything had enough time to start up and sync and when I sent the Close command it still opened. Here's the logs for that:

16:52:20	[D]	[ratgdo:228]	
Lock state=UNLOCKED
16:52:20	[D]	[ratgdo:113]	
Door state=STOPPED
16:52:20	[D]	[ratgdo:222]	
Light state=OFF
16:52:20	[D]	[ratgdo:228]	
Lock state=UNLOCKED
16:52:21	[D]	[ratgdo:222]	
Light state=OFF
16:52:21	[D]	[ratgdo:228]	
Lock state=UNLOCKED
16:52:21	[D]	[cover:076]	
'Door' - Setting
16:52:21	[D]	[cover:084]	
  Position: 0%
16:52:22	[D]	[ratgdo:228]	
Lock state=UNLOCKED
16:52:22	[D]	[ratgdo:222]	
Light state=ON
16:52:22	[D]	[ratgdo:228]	
Lock state=UNLOCKED
16:52:22	[D]	[ratgdo:222]	
Light state=ON
16:52:22	[D]	[ratgdo:228]	
Lock state=UNLOCKED
16:52:23	[D]	[ratgdo:222]	
Light state=ON
16:52:23	[D]	[ratgdo:228]	
Lock state=UNLOCKED
16:52:23	[D]	[ratgdo:113]	
Door state=OPENING
16:52:23	[D]	[cover:170]	
'Door' - Publishing:
16:52:23	[D]	[cover:173]	
  Position: 0%
16:52:23	[D]	[cover:186]	
  Current Operation: OPENING
16:52:23	[D]	[ratgdo:222]	
Light state=ON
16:52:23	[D]	[ratgdo:228]	
Lock state=UNLOCKED
16:52:23	[D]	[cover:170]	
'Door' - Publishing:
16:52:24	[D]	[cover:173]	
  Position: 4%
16:52:24	[D]	[cover:186]	
  Current Operation: OPENING
16:52:24	[D]	[ratgdo:222]	
Light state=ON
16:52:24	[D]	[ratgdo:228]	
Lock state=UNLOCKED
16:52:24	[D]	[ratgdo:113]	
Door state=OPENING
16:52:24	[D]	[cover:170]	
'Door' - Publishing:
16:52:24	[D]	[cover:173]	
  Position: 7%
16:52:24	[D]	[cover:186]	
  Current Operation: OPENING
16:52:24	[D]	[ratgdo:222]	
Light state=ON
16:52:24	[D]	[ratgdo:228]	
Lock state=UNLOCKED
16:52:25	[D]	[cover:170]	

The firmware adds discrete controls by comparing the requested command with the current door state and only sends the toggle command if the door state is what is expected.

I have been doing a similar thing in Home Assistant- only ever allowing a Close command to fire if it has Confirmed the Garage Door is in an Open state first. It's a reasonable enough work around considering its secplus1.