AlarmDecoder reports non-existent restore/fault events

[dssinger]

If I have several doors/windows open (6 is enough; I'm not sure about the lower limit) and a motion detector fires, AlarmDecoder reports a series of "restore/fault" events for every open door or window. Here's an example (I've replaced serial numbers for my sensors with the zone number for security):

Code: Select all

sqlite> select * from event_log where timestamp >= '2020-08-25 05:43:34' and timestamp <= '2020-08-25 05:44:02';
1475|17|2020-08-25 05:43:34|zone24:0:0:1:0:0:0
1476|19|2020-08-25 05:43:34|120600000000636c02456cf5ec0101010000
1477|19|2020-08-25 05:43:34|1606000000006463024543f531fb456cf5ec01020106
1478|8|2020-08-25 05:43:34|Zone FAMILY MOTION DETECTOR (24) has been faulted.
1479|17|2020-08-25 05:43:37|zone24:0:0:0:0:0:0
1480|19|2020-08-25 05:43:37|120600000000656c02456cf5ec0101010000
1481|19|2020-08-25 05:43:37|1606000000006663024543f531fb456cf5ec01020106
1482|9|2020-08-25 05:43:38|Zone MASTER BEDROOM WINDOW (13) has been restored.
1483|9|2020-08-25 05:43:41|Zone OFFICE WINDOW (14) has been restored.
1484|8|2020-08-25 05:43:45|Zone MASTER BEDROOM WINDOW (13) has been faulted.
1485|9|2020-08-25 05:43:45|Zone 1ST BEDROOM WINDOW (15) has been restored.
1486|8|2020-08-25 05:43:49|Zone OFFICE WINDOW (14) has been faulted.
1487|9|2020-08-25 05:43:49|Zone FAMILY SLIDING DOOR (16) has been restored.
1488|17|2020-08-25 05:43:50|zone12:0:1:1:1:0:0
1489|8|2020-08-25 05:43:53|Zone 1ST BEDROOM WINDOW (15) has been faulted.
1490|9|2020-08-25 05:43:53|Zone KITCHEN DESK WINDOW (17) has been restored.
1491|8|2020-08-25 05:43:57|Zone FAMILY SLIDING DOOR (16) has been faulted.
1492|9|2020-08-25 05:43:57|Zone SIDE BATHROOM WINDOW (19) has been restored.
1493|8|2020-08-25 05:44:01|Zone KITCHEN DESK WINDOW (17) has been faulted.


The log from the WebApp shows RFX events for windows which were open the whole time (I've sorted it earliest-to-latest):

Code: Select all

8/24/20 22:43   ZONE FAULT   Zone FAMILY MOTION DETECTOR (24) has been faulted.
8/24/20 22:43   RFX   zone24:0:0:0:0:0:0
8/24/20 22:43   AUI   120600000000656c02456cf5ec0101010000
8/24/20 22:43   AUI   1606000000006663024543f531fb456cf5ec01020106
8/24/20 22:43   ZONE RESTORE   Zone MASTER BEDROOM WINDOW (13) has been restored.
8/24/20 22:43   ZONE RESTORE   Zone OFFICE WINDOW (14) has been restored.
8/24/20 22:43   ZONE FAULT   Zone MASTER BEDROOM WINDOW (13) has been faulted.
8/24/20 22:43   ZONE RESTORE   Zone 1ST BEDROOM WINDOW (15) has been restored.
8/24/20 22:43   ZONE FAULT   Zone OFFICE WINDOW (14) has been faulted.
8/24/20 22:43   ZONE RESTORE   Zone FAMILY SLIDING DOOR (16) has been restored.
8/24/20 22:43   RFX   zone12:0:1:1:1:0:0
8/24/20 22:43   ZONE FAULT   Zone 1ST BEDROOM WINDOW (15) has been faulted.
8/24/20 22:43   ZONE RESTORE   Zone KITCHEN DESK WINDOW (17) has been restored.
8/24/20 22:43   ZONE FAULT   Zone FAMILY SLIDING DOOR (16) has been faulted.
8/24/20 22:43   ZONE RESTORE   Zone SIDE BATHROOM WINDOW (19) has been restored.
8/24/20 22:44   ZONE FAULT   Zone KITCHEN DESK WINDOW (17) has been faulted.
8/24/20 22:44   ZONE FAULT   Zone SIDE BATHROOM WINDOW (19) has been faulted.
8/24/20 22:44   ZONE RESTORE   Zone FAMILY MOTION DETECTOR (24) has been restored.
8/24/20 22:44   RFX   zone18:0:1:1:1:0:0
8/24/20 22:44   RFX   zone17:0:1:1:1:0:0
8/24/20 22:44   RFX   0253447:0:1:1:1:0:0
8/24/20 22:44   RFX   zone16:0:1:1:1:0:0
8/24/20 22:45   RFX   zone19:0:1:1:1:0:0
8/24/20 22:45   RFX   zone15:0:1:1:1:0:0
8/24/20 22:45   RFX   zone14:0:1:1:1:0:0


How can I help debug this?

[mathewss]

Please send your version and configuration settings via a 'C' and 'V' commands.

Best
SM

[dssinger]

Here you are:

Code: Select all

CONFIG>MODE=A&CONFIGBITS=ff00&ADDRESS=18&LRR=N&COM=N&EXP=NNNNN&REL=NNNN&MASK=ffffffff&DEDUPLICATE=Y
!Reading configuration.
!UART init.
!VER:ffffffff,V2.2a.8.9b-306,TX;RX;SM;VZ;RF;ZX;RE;AU;3X;CG;DD;MF;L2;KE;M2;CB;DS;ER;CR


BTW, the same thing happened with the old AD2PI but I didn't get around to reporting it.

Thanks.

David

[mathewss]

Interesting. I wish I could see the raw messages but I think I understand what you are seeing. Not sure how this is happening though since I dont think the logs have enough data. Please send me the link to the code base that you are running and I will review the code that is tracking zones.

If you can get me a raw log from the AD2* that would help me figure out how we got from A to B.

Best
SM

[dssinger]

It's the most current Buster-based download: http://www.alarmdecoder.com/downloads/p ... 90710B.ZIP

I updated it with the current changes from GitHub.

Is a ser2sock capture good enough for the debugging, or do you need something even closer to the metal?

I need to wait until it's not too hot and not too polluted to open the windows and run the test, but it looks plausible for tonight.

David

[mathewss]

Ser2sock is perfect! It shows me raw data then I will overlay that as best I can (no timestamps) with your database journel of what happend and I can then run each message through the same code on my end and track down what is happening.

Best
SM

[dssinger]

I ran the ser2sock capture through a program to add timestamps as I logged the stream and merged the database dump and the capture.

Here's the program (I'm using Python 3.8):

Code: Select all

#!/usr/bin/env python3
import sys
from datetime import datetime

while (l := sys.stdin.readline()):
    sys.stdout.write(f'{datetime.utcnow().strftime("%H:%M:%S")} {l}')
    sys.stdout.flush()


Here's the merged dump and capture - I've replaced the serial number of my devices with their zone number.

Code: Select all

04:49:03|0175358:0:1:1:1:0:0
04:49:08|Zone FAMILY SLIDING DOOR (16) has been faulted.
04:49:08|Zone MASTER BEDROOM MOTION (25) has been restored.
04:49:12|Zone KITCHEN (31) has been restored.
04:49:12|Zone KITCHEN DESK WINDOW (17) has been faulted.
04:49:16|Zone LIVING ROOM WINDOW (10) has been restored.
04:49:16|Zone SIDE BATHROOM WINDOW (19) has been faulted.
04:49:20|Zone KITCHEN (31) has been faulted.
04:49:20|Zone LIVING ROOM WINDOW (11) has been restored.
04:49:22|0044950:0:1:0:0:0:0
04:49:24|Zone LIVING ROOM WINDOW (10) has been faulted.
04:49:24|Zone MASTER BATHROOM WINDOW (12) has been restored.
04:49:27|Zone LIVING ROOM WINDOW (11) has been faulted.
04:49:28|Zone MASTER BEDROOM WINDOW (13) has been restored.
04:49:31|Zone MASTER BATHROOM WINDOW (12) has been faulted.
04:49:32|Zone OFFICE WINDOW (14) has been restored.
04:49:35 [00000001000000000A--],013,[f702000d1013000008020000000000],"FAULT 13 MASTER BEDROOM WINDOW  "
04:49:35|Zone MASTER BEDROOM WINDOW (13) has been faulted.
04:49:36|Zone 1ST BEDROOM WINDOW (15) has been restored.
04:49:39 [00000001000000000A--],014,[f702000d1014000008020000000000],"FAULT 14 OFFICE WINDOW          "
04:49:39|Zone FAMILY SLIDING DOOR (16) has been restored.
04:49:39|Zone OFFICE WINDOW (14) has been faulted.
04:49:43 [00000001000000000A--],015,[f702000d1015000008020000000000],"FAULT 15 1ST    BEDROOM WINDOW  "
04:49:43|Zone 1ST BEDROOM WINDOW (15) has been faulted.
04:49:43|Zone KITCHEN DESK WINDOW (17) has been restored.
04:49:47 [00000001000000000A--],016,[f702000d1016000008020000000000],"FAULT 16 FAMILY SLIDING DOOR    "
04:49:47|Zone FAMILY SLIDING DOOR (16) has been faulted.
04:49:47|Zone SIDE BATHROOM WINDOW (19) has been restored.
04:49:51 [00000001000000000A--],017,[f702000d1017000008020000000000],"FAULT 17 KITCHENDESK WINDOW     "
04:49:51|Zone KITCHEN (31) has been restored.
04:49:51|Zone KITCHEN DESK WINDOW (17) has been faulted.
04:49:55 [00000001000000000A--],019,[f702000d1019000008020000000000],"FAULT 19 SIDE   BATHROOM WINDOW "
04:49:55|Zone LIVING ROOM WINDOW (10) has been restored.
04:49:55|Zone SIDE BATHROOM WINDOW (19) has been faulted.
04:49:59 [00000001000000000A--],031,[f702000d1031000008020000000000],"FAULT 31 KITCHEN                "
04:49:59|Zone KITCHEN (31) has been faulted.
04:49:59|Zone LIVING ROOM WINDOW (11) has been restored.
04:50:03 [00000001000000000A--],010,[f702000d1010000008020000000000],"FAULT 10 LIVING ROOM WINDOW     "
04:50:03|Zone LIVING ROOM WINDOW (10) has been faulted.
04:50:03|Zone MASTER BATHROOM WINDOW (12) has been restored.
04:50:07 [00000001000000000A--],011,[f702000d1011000008020000000000],"FAULT 11 LIVING ROOM WINDOW     "
04:50:07|Zone LIVING ROOM WINDOW (11) has been faulted.
04:50:07|Zone MASTER BEDROOM WINDOW (13) has been restored.
04:50:11 [00000001000000000A--],012,[f702000d1012000008020000000000],"FAULT 12 MASTER BATHROOM WINDOW "
04:50:11|Zone MASTER BATHROOM WINDOW (12) has been faulted.
04:50:11|Zone OFFICE WINDOW (14) has been restored.
04:50:15 [00000001000000000A--],013,[f702000d1013000008020000000000],"FAULT 13 MASTER BEDROOM WINDOW  "
04:50:15|Zone 1ST BEDROOM WINDOW (15) has been restored.
04:50:15|Zone MASTER BEDROOM WINDOW (13) has been faulted.
04:50:19 [00000001000000000A--],014,[f702000d1014000008020000000000],"FAULT 14 OFFICE WINDOW          "
04:50:19|Zone FAMILY SLIDING DOOR (16) has been restored.
04:50:19|Zone OFFICE WINDOW (14) has been faulted.
04:50:23 [00000001000000000A--],015,[f702000d1015000008020000000000],"FAULT 15 1ST    BEDROOM WINDOW  "
04:50:23|Zone 1ST BEDROOM WINDOW (15) has been faulted.
04:50:23|Zone KITCHEN DESK WINDOW (17) has been restored.
04:50:27 [00000001000000000A--],016,[f702000d1016000008020000000000],"FAULT 16 FAMILY SLIDING DOOR    "
04:50:27|Zone FAMILY SLIDING DOOR (16) has been faulted.
04:50:27|Zone SIDE BATHROOM WINDOW (19) has been restored.
04:50:31 [00000001000000000A--],017,[f702000d1017000008020000000000],"FAULT 17 KITCHENDESK WINDOW     "
04:50:31|Zone KITCHEN (31) has been restored.
04:50:31|Zone KITCHEN DESK WINDOW (17) has been faulted.
04:50:35 [00000001000000000A--],019,[f702000d1019000008020000000000],"FAULT 19 SIDE   BATHROOM WINDOW "
04:50:35|Zone LIVING ROOM WINDOW (10) has been restored.
04:50:35|Zone SIDE BATHROOM WINDOW (19) has been faulted.
04:50:39 [00000001000000000A--],031,[f702000d1031000008020000000000],"FAULT 31 KITCHEN                "
04:50:39|Zone KITCHEN (31) has been faulted.
04:50:39|Zone LIVING ROOM WINDOW (11) has been restored.
04:50:43 [00000001000000000A--],010,[f702000d1010000008020000000000],"FAULT 10 LIVING ROOM WINDOW     "
04:50:43|Zone LIVING ROOM WINDOW (10) has been faulted.
04:50:43|Zone MASTER BATHROOM WINDOW (12) has been restored.
04:50:47 [00000001000000000A--],011,[f702000d1011000008020000000000],"FAULT 11 LIVING ROOM WINDOW     "
04:50:47|Zone LIVING ROOM WINDOW (11) has been faulted.
04:50:47|Zone MASTER BEDROOM WINDOW (13) has been restored.
04:50:50 [00000001000000000A--],012,[f702000d1012000008020000000000],"FAULT 12 MASTER BATHROOM WINDOW "
04:50:51|Zone MASTER BATHROOM WINDOW (12) has been faulted.
04:50:51|Zone OFFICE WINDOW (14) has been restored.
04:50:54 [00000001000000000A--],013,[f702000d1013000008020000000000],"FAULT 13 MASTER BEDROOM WINDOW  "
04:50:55|Zone 1ST BEDROOM WINDOW (15) has been restored.
04:50:55|Zone MASTER BEDROOM WINDOW (13) has been faulted.
04:50:58 [00000001000000000A--],014,[f702000d1014000008020000000000],"FAULT 14 OFFICE WINDOW          "
04:50:59|Zone FAMILY SLIDING DOOR (16) has been restored.
04:50:59|Zone OFFICE WINDOW (14) has been faulted.
04:51:01 !RFX:zone24,80
04:51:01|zone24:0:0:1:0:0:0
04:51:02 [00000001000000000A--],024,[f702000d10240000080200000010f0],"FAULT 24 FAMILY MOTION DETECTOR "
04:51:02|Zone FAMILY MOTION DETECTOR (24) has been faulted.
04:51:02|Zone KITCHEN DESK WINDOW (17) has been restored.
04:51:05 !RFX:zone24,00
04:51:05 [00000001000000000A--],031,[f702000d1031000008020000000000],"FAULT 31 KITCHEN                "
04:51:05|Zone SIDE BATHROOM WINDOW (19) has been restored.
04:51:05|zone24:0:0:0:0:0:0
04:51:09 [00000001000000000A--],010,[f702000d1010000008020000000000],"FAULT 10 LIVING ROOM WINDOW     "
04:51:13 [00000001000000000A--],011,[f702000d1011000008020000000000],"FAULT 11 LIVING ROOM WINDOW     "
04:51:17 [00000001000000000A--],012,[f702000d1012000008020000000000],"FAULT 12 MASTER BATHROOM WINDOW "
04:51:20 [00000001000000000A--],013,[f702000d1013000008020000000000],"FAULT 13 MASTER BEDROOM WINDOW  "
04:51:24 [00000001000000000A--],014,[f702000d1014000008020000000000],"FAULT 14 OFFICE WINDOW          "
04:51:28 [00000001000000000A--],015,[f702000d1015000008020000000000],"FAULT 15 1ST    BEDROOM WINDOW  "
04:51:29|Zone 1ST BEDROOM WINDOW (15) has been faulted.
04:51:32 [00000001000000000A--],016,[f702000d1016000008020000000000],"FAULT 16 FAMILY SLIDING DOOR    "
04:51:33|Zone FAMILY MOTION DETECTOR (24) has been restored.
04:51:33|Zone FAMILY SLIDING DOOR (16) has been faulted.
04:51:35 !RFX:zone12,a4
04:51:35|zone12:0:1:1:1:0:0
04:51:36 [00000001000000000A--],017,[f702000d1017000008020000000000],"FAULT 17 KITCHENDESK WINDOW     "
04:51:36|Zone KITCHEN DESK WINDOW (17) has been faulted.
04:51:37|Zone KITCHEN (31) has been restored.
04:51:40 [00000001000000000A--],019,[f702000d1019000008020000000000],"FAULT 19 SIDE   BATHROOM WINDOW "
04:51:40|Zone SIDE BATHROOM WINDOW (19) has been faulted.
04:51:41|Zone LIVING ROOM WINDOW (10) has been restored.
04:51:44 [00000001000000000A--],031,[f702000d1031000008020000000000],"FAULT 31 KITCHEN                "
04:51:44|Zone KITCHEN (31) has been faulted.
04:51:45|Zone LIVING ROOM WINDOW (11) has been restored.
04:51:48 [00000001000000000A--],010,[f702000d1010000008020000000000],"FAULT 10 LIVING ROOM WINDOW     "
04:51:48|Zone LIVING ROOM WINDOW (10) has been faulted.
04:51:48|Zone MASTER BATHROOM WINDOW (12) has been restored.
04:51:52 [00000001000000000A--],011,[f702000d1011000008020000000000],"FAULT 11 LIVING ROOM WINDOW     "
04:51:52|Zone LIVING ROOM WINDOW (11) has been faulted.
04:51:52|Zone MASTER BEDROOM WINDOW (13) has been restored.
04:51:56 [00000001000000000A--],012,[f702000d1012000008020000000000],"FAULT 12 MASTER BATHROOM WINDOW "
04:51:56|Zone MASTER BATHROOM WINDOW (12) has been faulted.
04:51:56|Zone OFFICE WINDOW (14) has been restored.
04:52:00 [00000001000000000A--],013,[f702000d1013000008020000000000],"FAULT 13 MASTER BEDROOM WINDOW  "
04:52:00|Zone 1ST BEDROOM WINDOW (15) has been restored.
04:52:00|Zone MASTER BEDROOM WINDOW (13) has been faulted.
04:52:04 [00000001000000000A--],014,[f702000d1014000008020000000000],"FAULT 14 OFFICE WINDOW          "
04:52:04|Zone FAMILY SLIDING DOOR (16) has been restored.
04:52:04|Zone OFFICE WINDOW (14) has been faulted.
04:52:08 [00000001000000000A--],015,[f702000d1015000008020000000000],"FAULT 15 1ST    BEDROOM WINDOW  "
04:52:08|Zone 1ST BEDROOM WINDOW (15) has been faulted.
04:52:08|Zone KITCHEN DESK WINDOW (17) has been restored.
04:52:12 [00000001000000000A--],016,[f702000d1016000008020000000000],"FAULT 16 FAMILY SLIDING DOOR    "
04:52:12|Zone FAMILY SLIDING DOOR (16) has been faulted.
04:52:12|Zone SIDE BATHROOM WINDOW (19) has been restored.
04:52:16 [00000001000000000A--],017,[f702000d1017000008020000000000],"FAULT 17 KITCHENDESK WINDOW     "
04:52:16|Zone KITCHEN (31) has been restored.
04:52:16|Zone KITCHEN DESK WINDOW (17) has been faulted.
04:52:17 !RFX:zone13,a4
04:52:17|zone13:0:1:1:1:0:0
04:52:20 [00000001000000000A--],019,[f702000d1019000008020000000000],"FAULT 19 SIDE   BATHROOM WINDOW "
04:52:20|Zone LIVING ROOM WINDOW (10) has been restored.
04:52:20|Zone SIDE BATHROOM WINDOW (19) has been faulted.
04:52:24 [00000001000000000A--],031,[f702000d1031000008020000000000],"FAULT 31 KITCHEN                "
04:52:24|Zone KITCHEN (31) has been faulted.
04:52:24|Zone LIVING ROOM WINDOW (11) has been restored.
04:52:28 [00000001000000000A--],010,[f702000d1010000008020000000000],"FAULT 10 LIVING ROOM WINDOW     "
04:52:28|Zone LIVING ROOM WINDOW (10) has been faulted.
04:52:28|Zone MASTER BATHROOM WINDOW (12) has been restored.


I looked at the code in alarmdecoder/zonetracking.py and I wonder if the problem is as simple as not allowing a longer time between sightings of a zone if many are open - it looks like the panel sends out one "Fault" message every four seconds or so, so with 7 or 8 faulted zones, the timer would expire before the zone came back around.

[mathewss]

This sounds like the same issue(s).
https://github.com/nutechsoftware/alarmdecoder/pull/53

I reviewed the supplied patch yesterday and was planning on merging it into the 'dev' branch to test. Please switch to the dev branch if you can help me test that would be excellent. I just released a serial driver code improvement that needs testing as well

Best
SM

[dssinger]

I just switched the webapp and the API to the dev branch and restarted. When I see an update available, I'll take it and try my testing again.

[mathewss]

Ok the EXP / ZONE fix is now in dev. I am testing currently and so far nothing is broken