Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

Zone program mode (*56) incorrectly changes state of binary sensor alarm zone #94

Open
bdf0506 opened this issue Feb 12, 2024 · 7 comments
Open

Zone program mode (*56) incorrectly changes state of binary sensor alarm zone #94

bdf0506 opened this issue Feb 12, 2024 · 7 comments

Comments

@bdf0506
Copy link

bdf0506 commented Feb 12, 2024

I have an Envisalink 4 on a Honeywell Vista 20p system. If you go into zone program mode (*56), whenever you select the zone, it will change the state of the binary sensor for that zone, even though that binary sensor has not actually been tripped. So in my case, I have a few automations when zone 1 (fire) triggers and clears. Simply going into zone program mode will change the binary sensor from fire from off to on. Since when you go into zone program mode, it defaults to zone 1, each time that you go into zone program mode, it will change the state of the fire sensor - and if you enter another zone number, that one will now also flip from off to on. Unsure if this is actually a problem with this Envisalink module for HA, or perhaps it is something upstream in the pyenvisalink package.

To reproduce:

  1. On a Honeywell Vista panel, enter "4112800" to enter programming mode, assuming that you have not changed it from the default.
  2. Hit *56 to enter programming mode.
  3. Hit 1 to continue.
  4. At this point, the fire sensor (assuming that it is zone 1 which is the system default) should change from off to on.
  5. To further test, change zone number to another zone, and that sensor will also change from off to on.
  6. Enter 00 to exit programing mode, and *99 to exit. Soon, the zone should clear to closed.

I do have syslog enabled on the Envisalink config. When this condition happens, the syslog is clear, there is no reference to Zone 1 opening.

Relevant debug log:

2024-02-12 10:58:31.978 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Setting last fault for 1: 1707753511.9788084

Full debug logs:

2024-02-12 10:58:22.533 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-02-12 10:58:22.533 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-02-12 10:58:23.152 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-02-12 10:58:23.153 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < %00,01,1C08,08,00,****DISARMED****  Ready to Arm  $

2024-02-12 10:58:23.153 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Data received:%00,01,1C08,08,00,****DISARMED****  Ready to Arm  $
2024-02-12 10:58:23.153 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Code:%00 Data:01,1C08,08,00,****DISARMED****  Ready to Arm  
2024-02-12 10:58:23.153 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_keypad_update for code: %00 with data: 01,1C08,08,00,****DISARMED****  Ready to Arm  
2024-02-12 10:58:23.153 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Keypad update is giving partition 1 status. Partition: ready Zonecode: unknown
2024-02-12 10:58:23.153 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] {"partition_state": "N/A", "alpha": "****DISARMED****  Ready to Arm  ", "ac_present": true, "beep": "off", "armed_bypass": false, "entry_delay": false, "exit_delay": false, "last_armed_by_user": "", "last_disarmed_by_user": "", "ready": true, "bat_trouble": false, "trouble": false, "fire": false, "panic": false, "alarm": false, "alarm_fire_zone": false, "alarm_in_memory": false, "armed_away": false, "armed_stay": false, "armed_zero_entry_delay": false, "chime": false}
2024-02-12 10:58:23.153 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-02-12 10:58:23.153 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Triggering state change callback for partition: [1]
2024-02-12 10:58:23.153 DEBUG (MainThread) [custom_components.envisalink_new] The envisalink 'Alarm' sent a partition update event: [1]
2024-02-12 10:58:23.153 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm'
2024-02-12 10:58:23.153 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm AC Power'
2024-02-12 10:58:23.153 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Ready'
2024-02-12 10:58:23.153 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Panel Battery'
2024-02-12 10:58:23.153 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Panel Health'
2024-02-12 10:58:23.153 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Fire'
2024-02-12 10:58:23.153 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Alarm'
2024-02-12 10:58:23.153 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Keypad'
2024-02-12 10:58:23.153 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Panel Chime'
2024-02-12 10:58:23.153 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-02-12 10:58:23.153 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-02-12 10:58:24.291 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-02-12 10:58:24.291 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < %02,0300000000000000$

2024-02-12 10:58:24.291 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Data received:%02,0300000000000000$
2024-02-12 10:58:24.291 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Code:%02 Data:0300000000000000
2024-02-12 10:58:24.291 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_partition_state_change for code: %02 with data: 0300000000000000
2024-02-12 10:58:24.291 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-02-12 10:58:24.291 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-02-12 10:58:24.291 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-02-12 10:58:24.292 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-02-12 10:58:24.292 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < %00,01,0040,20,00,Installer Code                20$

2024-02-12 10:58:24.292 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Data received:%00,01,0040,20,00,Installer Code                20$
2024-02-12 10:58:24.292 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Code:%00 Data:01,0040,20,00,Installer Code                20
2024-02-12 10:58:24.292 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_keypad_update for code: %00 with data: 01,0040,20,00,Installer Code                20
2024-02-12 10:58:24.292 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Keypad is giving zone status for partition 1.
2024-02-12 10:58:24.292 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Setting last fault for 20: 1707753504.2921407
2024-02-12 10:58:24.292 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Timer 20|state :: 1
2024-02-12 10:58:24.292 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] There are (1) active timers
2024-02-12 10:58:24.292 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] {"partition_state": "N/A", "alpha": "Installer Code                20", "ac_present": false, "beep": "off", "armed_bypass": false, "entry_delay": false, "exit_delay": false, "last_armed_by_user": "", "last_disarmed_by_user": "", "ready": false, "bat_trouble": false, "trouble": false, "fire": false, "panic": false, "alarm": false, "alarm_fire_zone": false, "alarm_in_memory": false, "armed_away": false, "armed_stay": false, "armed_zero_entry_delay": false, "chime": false}
2024-02-12 10:58:24.292 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-02-12 10:58:24.292 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Triggering state change callback for partition: [1]
2024-02-12 10:58:24.292 DEBUG (MainThread) [custom_components.envisalink_new] The envisalink 'Alarm' sent a partition update event: [1]
2024-02-12 10:58:24.292 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm'
2024-02-12 10:58:24.292 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm AC Power'
2024-02-12 10:58:24.292 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Ready'
2024-02-12 10:58:24.292 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Panel Battery'
2024-02-12 10:58:24.292 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Panel Health'
2024-02-12 10:58:24.292 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Fire'
2024-02-12 10:58:24.292 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Alarm'
2024-02-12 10:58:24.292 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Keypad'
2024-02-12 10:58:24.292 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Panel Chime'
2024-02-12 10:58:24.292 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Triggering state change callback for zone: [20]
2024-02-12 10:58:24.293 DEBUG (MainThread) [custom_components.envisalink_new] Envisalink sent a 'Alarm' zone update event. Updating zones: [20]
2024-02-12 10:58:24.293 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-02-12 10:58:24.293 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-02-12 10:58:29.294 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-02-12 10:58:29.479 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-02-12 10:58:29.479 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < %00,01,0C40,FF,00,Field?                          $

2024-02-12 10:58:29.479 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Data received:%00,01,0C40,FF,00,Field?                          $
2024-02-12 10:58:29.479 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Code:%00 Data:01,0C40,FF,00,Field?                          
2024-02-12 10:58:29.479 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_keypad_update for code: %00 with data: 01,0C40,FF,00,Field?                          
2024-02-12 10:58:29.479 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Keypad update is giving partition 1 status. Partition: notready Zonecode: notready
2024-02-12 10:58:29.479 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] {"partition_state": "N/A", "alpha": "Field?                          ", "ac_present": false, "beep": "off", "armed_bypass": false, "entry_delay": false, "exit_delay": false, "last_armed_by_user": "", "last_disarmed_by_user": "", "ready": false, "bat_trouble": false, "trouble": false, "fire": false, "panic": false, "alarm": false, "alarm_fire_zone": false, "alarm_in_memory": false, "armed_away": false, "armed_stay": false, "armed_zero_entry_delay": false, "chime": false}
2024-02-12 10:58:29.479 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-02-12 10:58:29.479 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Triggering state change callback for partition: [1]
2024-02-12 10:58:29.479 DEBUG (MainThread) [custom_components.envisalink_new] The envisalink 'Alarm' sent a partition update event: [1]
2024-02-12 10:58:29.479 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm'
2024-02-12 10:58:29.479 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm AC Power'
2024-02-12 10:58:29.479 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Ready'
2024-02-12 10:58:29.479 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Panel Battery'
2024-02-12 10:58:29.479 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Panel Health'
2024-02-12 10:58:29.479 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Fire'
2024-02-12 10:58:29.479 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Alarm'
2024-02-12 10:58:29.479 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Keypad'
2024-02-12 10:58:29.479 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Panel Chime'
2024-02-12 10:58:29.480 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-02-12 10:58:29.480 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-02-12 10:58:30.138 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-02-12 10:58:30.138 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < %00,01,0840,F5,00,Field?                         5$

2024-02-12 10:58:30.139 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Data received:%00,01,0840,F5,00,Field?                         5$
2024-02-12 10:58:30.139 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Code:%00 Data:01,0840,F5,00,Field?                         5
2024-02-12 10:58:30.139 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_keypad_update for code: %00 with data: 01,0840,F5,00,Field?                         5
2024-02-12 10:58:30.139 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] {"partition_state": "N/A", "alpha": "Field?                         5", "ac_present": false, "beep": "off", "armed_bypass": false, "entry_delay": false, "exit_delay": false, "last_armed_by_user": "", "last_disarmed_by_user": "", "ready": false, "bat_trouble": false, "trouble": false, "fire": false, "panic": false, "alarm": false, "alarm_fire_zone": false, "alarm_in_memory": false, "armed_away": false, "armed_stay": false, "armed_zero_entry_delay": false, "chime": false}
2024-02-12 10:58:30.139 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-02-12 10:58:30.139 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Triggering state change callback for partition: [1]
2024-02-12 10:58:30.139 DEBUG (MainThread) [custom_components.envisalink_new] The envisalink 'Alarm' sent a partition update event: [1]
2024-02-12 10:58:30.139 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm'
2024-02-12 10:58:30.139 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm AC Power'
2024-02-12 10:58:30.139 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Ready'
2024-02-12 10:58:30.139 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Panel Battery'
2024-02-12 10:58:30.139 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Panel Health'
2024-02-12 10:58:30.139 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Fire'
2024-02-12 10:58:30.139 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Alarm'
2024-02-12 10:58:30.139 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Keypad'
2024-02-12 10:58:30.140 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Panel Chime'
2024-02-12 10:58:30.140 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-02-12 10:58:30.140 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-02-12 10:58:30.475 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-02-12 10:58:30.475 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < %00,01,0C40,F5,00,Set to Confirm? 0=No,1=Yes     0$

2024-02-12 10:58:30.475 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Data received:%00,01,0C40,F5,00,Set to Confirm? 0=No,1=Yes     0$
2024-02-12 10:58:30.475 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Code:%00 Data:01,0C40,F5,00,Set to Confirm? 0=No,1=Yes     0
2024-02-12 10:58:30.475 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_keypad_update for code: %00 with data: 01,0C40,F5,00,Set to Confirm? 0=No,1=Yes     0
2024-02-12 10:58:30.476 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Keypad update is giving partition 1 status. Partition: notready Zonecode: notready
2024-02-12 10:58:30.476 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] {"partition_state": "N/A", "alpha": "Set to Confirm? 0=No,1=Yes     0", "ac_present": false, "beep": "off", "armed_bypass": false, "entry_delay": false, "exit_delay": false, "last_armed_by_user": "", "last_disarmed_by_user": "", "ready": false, "bat_trouble": false, "trouble": false, "fire": false, "panic": false, "alarm": false, "alarm_fire_zone": false, "alarm_in_memory": false, "armed_away": false, "armed_stay": false, "armed_zero_entry_delay": false, "chime": false}
2024-02-12 10:58:30.476 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-02-12 10:58:30.476 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Triggering state change callback for partition: [1]
2024-02-12 10:58:30.476 DEBUG (MainThread) [custom_components.envisalink_new] The envisalink 'Alarm' sent a partition update event: [1]
2024-02-12 10:58:30.476 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm'
2024-02-12 10:58:30.476 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm AC Power'
2024-02-12 10:58:30.476 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Ready'
2024-02-12 10:58:30.476 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Panel Battery'
2024-02-12 10:58:30.476 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Panel Health'
2024-02-12 10:58:30.476 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Fire'
2024-02-12 10:58:30.476 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Alarm'
2024-02-12 10:58:30.476 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Keypad'
2024-02-12 10:58:30.477 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Panel Chime'
2024-02-12 10:58:30.477 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-02-12 10:58:30.477 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-02-12 10:58:31.978 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-02-12 10:58:31.978 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < %00,01,0040,01,00,Enter Zn Num.   (00=Quit)     01$

2024-02-12 10:58:31.978 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Data received:%00,01,0040,01,00,Enter Zn Num.   (00=Quit)     01$
2024-02-12 10:58:31.978 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Code:%00 Data:01,0040,01,00,Enter Zn Num.   (00=Quit)     01
2024-02-12 10:58:31.978 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_keypad_update for code: %00 with data: 01,0040,01,00,Enter Zn Num.   (00=Quit)     01
2024-02-12 10:58:31.978 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Keypad is giving zone status for partition 1.
2024-02-12 10:58:31.978 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Setting last fault for 1: 1707753511.9788084
2024-02-12 10:58:31.978 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Timer 20|state :: 2
2024-02-12 10:58:31.978 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Timer 1|state :: 1
2024-02-12 10:58:31.978 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] There are (2) active timers
2024-02-12 10:58:31.978 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] {"partition_state": "N/A", "alpha": "Enter Zn Num.   (00=Quit)     01", "ac_present": false, "beep": "off", "armed_bypass": false, "entry_delay": false, "exit_delay": false, "last_armed_by_user": "", "last_disarmed_by_user": "", "ready": false, "bat_trouble": false, "trouble": false, "fire": false, "panic": false, "alarm": false, "alarm_fire_zone": false, "alarm_in_memory": false, "armed_away": false, "armed_stay": false, "armed_zero_entry_delay": false, "chime": false}
2024-02-12 10:58:31.978 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-02-12 10:58:31.979 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Triggering state change callback for partition: [1]
2024-02-12 10:58:31.979 DEBUG (MainThread) [custom_components.envisalink_new] The envisalink 'Alarm' sent a partition update event: [1]
2024-02-12 10:58:31.979 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm'
2024-02-12 10:58:31.979 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm AC Power'
2024-02-12 10:58:31.979 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Ready'
2024-02-12 10:58:31.979 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Panel Battery'
2024-02-12 10:58:31.979 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Panel Health'
2024-02-12 10:58:31.979 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Fire'
2024-02-12 10:58:31.979 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Alarm'
2024-02-12 10:58:31.979 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Keypad'
2024-02-12 10:58:31.979 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Panel Chime'
2024-02-12 10:58:31.979 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Triggering state change callback for zone: [1]
2024-02-12 10:58:31.979 DEBUG (MainThread) [custom_components.envisalink_new] Envisalink sent a 'Alarm' zone update event. Updating zones: [1]
2024-02-12 10:58:31.979 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Fire'
2024-02-12 10:58:31.979 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-02-12 10:58:31.979 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-02-12 10:58:31.987 INFO (MainThread) [homeassistant.components.automation.fire_alarm_active] Fire Alarm Active: Running automation actions
2024-02-12 10:58:31.987 INFO (MainThread) [homeassistant.components.automation.fire_alarm_active] Fire Alarm Active: Executing step call service
2024-02-12 10:58:31.987 INFO (MainThread) [homeassistant.components.automation.state_change_to_input_date] State change to input date: Running automation actions
2024-02-12 10:58:31.987 INFO (MainThread) [homeassistant.components.automation.state_change_to_input_date] State change to input date: Executing step call service
2024-02-12 10:58:31.995 ERROR (MainThread) [homeassistant.components.automation.fire_alarm_active] Fire Alarm Active: Error executing script. Invalid data for call_service at pos 1: required key not provided @ data['message']
2024-02-12 10:58:32.000 ERROR (MainThread) [homeassistant.components.automation.fire_alarm_active] Error while executing automation automation.fire_alarm_active: required key not provided @ data['message']
2024-02-12 10:58:34.758 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-02-12 10:58:34.758 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < %00,01,0040,01,00,Enter Zn Num.   (00=Quit)     01$

2024-02-12 10:58:34.758 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Data received:%00,01,0040,01,00,Enter Zn Num.   (00=Quit)     01$
2024-02-12 10:58:34.758 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Code:%00 Data:01,0040,01,00,Enter Zn Num.   (00=Quit)     01
2024-02-12 10:58:34.758 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_keypad_update for code: %00 with data: 01,0040,01,00,Enter Zn Num.   (00=Quit)     01
2024-02-12 10:58:34.758 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Keypad is giving zone status for partition 1.
2024-02-12 10:58:34.758 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Timer 20|state :: 3
2024-02-12 10:58:34.758 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Timer 1|state :: 1
2024-02-12 10:58:34.758 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] There are (2) active timers
2024-02-12 10:58:34.758 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] {"partition_state": "N/A", "alpha": "Enter Zn Num.   (00=Quit)     01", "ac_present": false, "beep": "off", "armed_bypass": false, "entry_delay": false, "exit_delay": false, "last_armed_by_user": "", "last_disarmed_by_user": "", "ready": false, "bat_trouble": false, "trouble": false, "fire": false, "panic": false, "alarm": false, "alarm_fire_zone": false, "alarm_in_memory": false, "armed_away": false, "armed_stay": false, "armed_zero_entry_delay": false, "chime": false}
2024-02-12 10:58:34.759 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-02-12 10:58:34.759 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Triggering state change callback for partition: [1]
2024-02-12 10:58:34.759 DEBUG (MainThread) [custom_components.envisalink_new] The envisalink 'Alarm' sent a partition update event: [1]
2024-02-12 10:58:34.759 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm'
2024-02-12 10:58:34.759 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm AC Power'
2024-02-12 10:58:34.759 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Ready'
2024-02-12 10:58:34.759 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Panel Battery'
2024-02-12 10:58:34.759 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Panel Health'
2024-02-12 10:58:34.759 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Fire'
2024-02-12 10:58:34.759 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Alarm'
2024-02-12 10:58:34.759 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Keypad'
2024-02-12 10:58:34.759 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Panel Chime'
2024-02-12 10:58:34.759 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Triggering state change callback for zone: [1]
2024-02-12 10:58:34.759 DEBUG (MainThread) [custom_components.envisalink_new] Envisalink sent a 'Alarm' zone update event. Updating zones: [1]
2024-02-12 10:58:34.759 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Fire'
2024-02-12 10:58:34.760 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-02-12 10:58:34.760 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-02-12 10:58:35.093 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-02-12 10:58:35.093 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < %00,01,0C40,FF,00,Enter * or #                    $

2024-02-12 10:58:35.093 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Data received:%00,01,0C40,FF,00,Enter * or #                    $
2024-02-12 10:58:35.093 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Code:%00 Data:01,0C40,FF,00,Enter * or #                    
2024-02-12 10:58:35.093 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_keypad_update for code: %00 with data: 01,0C40,FF,00,Enter * or #                    
2024-02-12 10:58:35.093 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Keypad update is giving partition 1 status. Partition: notready Zonecode: notready
2024-02-12 10:58:35.093 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] {"partition_state": "N/A", "alpha": "Enter * or #                    ", "ac_present": false, "beep": "off", "armed_bypass": false, "entry_delay": false, "exit_delay": false, "last_armed_by_user": "", "last_disarmed_by_user": "", "ready": false, "bat_trouble": false, "trouble": false, "fire": false, "panic": false, "alarm": false, "alarm_fire_zone": false, "alarm_in_memory": false, "armed_away": false, "armed_stay": false, "armed_zero_entry_delay": false, "chime": false}
2024-02-12 10:58:35.093 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-02-12 10:58:35.093 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Triggering state change callback for partition: [1]
2024-02-12 10:58:35.093 DEBUG (MainThread) [custom_components.envisalink_new] The envisalink 'Alarm' sent a partition update event: [1]
2024-02-12 10:58:35.093 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm'
2024-02-12 10:58:35.093 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm AC Power'
2024-02-12 10:58:35.093 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Ready'
2024-02-12 10:58:35.094 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Panel Battery'
2024-02-12 10:58:35.094 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Panel Health'
2024-02-12 10:58:35.094 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Fire'
2024-02-12 10:58:35.094 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Alarm'
2024-02-12 10:58:35.094 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Keypad'
2024-02-12 10:58:35.094 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Panel Chime'
2024-02-12 10:58:35.094 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-02-12 10:58:35.094 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-02-12 10:58:36.078 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-02-12 10:58:36.078 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < %00,01,0C40,FF,00,Field?                          $

2024-02-12 10:58:36.078 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Data received:%00,01,0C40,FF,00,Field?                          $
2024-02-12 10:58:36.078 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Code:%00 Data:01,0C40,FF,00,Field?                          
2024-02-12 10:58:36.078 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_keypad_update for code: %00 with data: 01,0C40,FF,00,Field?                          
2024-02-12 10:58:36.078 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Keypad update is giving partition 1 status. Partition: notready Zonecode: notready
2024-02-12 10:58:36.078 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] {"partition_state": "N/A", "alpha": "Field?                          ", "ac_present": false, "beep": "off", "armed_bypass": false, "entry_delay": false, "exit_delay": false, "last_armed_by_user": "", "last_disarmed_by_user": "", "ready": false, "bat_trouble": false, "trouble": false, "fire": false, "panic": false, "alarm": false, "alarm_fire_zone": false, "alarm_in_memory": false, "armed_away": false, "armed_stay": false, "armed_zero_entry_delay": false, "chime": false}
2024-02-12 10:58:36.078 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-02-12 10:58:36.078 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Triggering state change callback for partition: [1]
2024-02-12 10:58:36.078 DEBUG (MainThread) [custom_components.envisalink_new] The envisalink 'Alarm' sent a partition update event: [1]
2024-02-12 10:58:36.078 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm'
2024-02-12 10:58:36.078 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm AC Power'
2024-02-12 10:58:36.078 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Ready'
2024-02-12 10:58:36.078 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Panel Battery'
2024-02-12 10:58:36.078 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Panel Health'
2024-02-12 10:58:36.078 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Fire'
2024-02-12 10:58:36.078 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Alarm'
2024-02-12 10:58:36.078 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Keypad'
2024-02-12 10:58:36.079 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Panel Chime'
2024-02-12 10:58:36.079 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-02-12 10:58:36.079 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-02-12 10:58:36.738 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-02-12 10:58:36.738 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < %00,01,0840,F9,00,Field?                         9$

2024-02-12 10:58:36.738 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Data received:%00,01,0840,F9,00,Field?                         9$
2024-02-12 10:58:36.738 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Code:%00 Data:01,0840,F9,00,Field?                         9
2024-02-12 10:58:36.738 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_keypad_update for code: %00 with data: 01,0840,F9,00,Field?                         9
2024-02-12 10:58:36.738 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] {"partition_state": "N/A", "alpha": "Field?                         9", "ac_present": false, "beep": "off", "armed_bypass": false, "entry_delay": false, "exit_delay": false, "last_armed_by_user": "", "last_disarmed_by_user": "", "ready": false, "bat_trouble": false, "trouble": false, "fire": false, "panic": false, "alarm": false, "alarm_fire_zone": false, "alarm_in_memory": false, "armed_away": false, "armed_stay": false, "armed_zero_entry_delay": false, "chime": false}
2024-02-12 10:58:36.738 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-02-12 10:58:36.738 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Triggering state change callback for partition: [1]
2024-02-12 10:58:36.738 DEBUG (MainThread) [custom_components.envisalink_new] The envisalink 'Alarm' sent a partition update event: [1]
2024-02-12 10:58:36.738 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm'
2024-02-12 10:58:36.738 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm AC Power'
2024-02-12 10:58:36.738 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Ready'
2024-02-12 10:58:36.738 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Panel Battery'
2024-02-12 10:58:36.738 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Panel Health'
2024-02-12 10:58:36.738 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Fire'
2024-02-12 10:58:36.738 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Alarm'
2024-02-12 10:58:36.738 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Keypad'
2024-02-12 10:58:36.739 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Panel Chime'
2024-02-12 10:58:36.739 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-02-12 10:58:36.739 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-02-12 10:58:38.645 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-02-12 10:58:38.646 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < %02,0100000000000000$

2024-02-12 10:58:38.646 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Data received:%02,0100000000000000$
2024-02-12 10:58:38.646 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Code:%02 Data:0100000000000000
2024-02-12 10:58:38.646 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_partition_state_change for code: %02 with data: 0100000000000000
2024-02-12 10:58:38.646 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-02-12 10:58:38.646 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-02-12 10:58:38.646 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-02-12 10:58:38.646 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-02-12 10:58:38.646 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < %00,01,1C08,08,00,****DISARMED****  Ready to Arm  $

2024-02-12 10:58:38.646 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Data received:%00,01,1C08,08,00,****DISARMED****  Ready to Arm  $
2024-02-12 10:58:38.646 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Code:%00 Data:01,1C08,08,00,****DISARMED****  Ready to Arm  
2024-02-12 10:58:38.646 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_keypad_update for code: %00 with data: 01,1C08,08,00,****DISARMED****  Ready to Arm  
2024-02-12 10:58:38.646 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Clear partition 1
2024-02-12 10:58:38.646 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Timer 20|state :: 3 Closing
2024-02-12 10:58:38.646 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Timer 1|state :: 1 Closing
2024-02-12 10:58:38.646 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Keypad update is giving partition 1 status. Partition: ready Zonecode: unknown
2024-02-12 10:58:38.646 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] {"partition_state": "N/A", "alpha": "****DISARMED****  Ready to Arm  ", "ac_present": true, "beep": "off", "armed_bypass": false, "entry_delay": false, "exit_delay": false, "last_armed_by_user": "", "last_disarmed_by_user": "", "ready": true, "bat_trouble": false, "trouble": false, "fire": false, "panic": false, "alarm": false, "alarm_fire_zone": false, "alarm_in_memory": false, "armed_away": false, "armed_stay": false, "armed_zero_entry_delay": false, "chime": false}
2024-02-12 10:58:38.646 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-02-12 10:58:38.646 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Triggering state change callback for partition: [1]
2024-02-12 10:58:38.646 DEBUG (MainThread) [custom_components.envisalink_new] The envisalink 'Alarm' sent a partition update event: [1]
2024-02-12 10:58:38.646 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm'
2024-02-12 10:58:38.646 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm AC Power'
2024-02-12 10:58:38.646 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Ready'
2024-02-12 10:58:38.647 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Panel Battery'
2024-02-12 10:58:38.647 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Panel Health'
2024-02-12 10:58:38.647 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Fire'
2024-02-12 10:58:38.647 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Alarm'
2024-02-12 10:58:38.647 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Keypad'
2024-02-12 10:58:38.647 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Panel Chime'
2024-02-12 10:58:38.647 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Triggering state change callback for zone: [20, 1]
2024-02-12 10:58:38.647 DEBUG (MainThread) [custom_components.envisalink_new] Envisalink sent a 'Alarm' zone update event. Updating zones: [20, 1]
2024-02-12 10:58:38.647 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Fire'
2024-02-12 10:58:38.647 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-02-12 10:58:38.647 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-02-12 10:58:38.655 INFO (MainThread) [homeassistant.components.automation.fire_alarm_cleared] Fire Alarm Cleared: Running automation actions
2024-02-12 10:58:38.655 INFO (MainThread) [homeassistant.components.automation.fire_alarm_cleared] Fire Alarm Cleared: Executing step call service
2024-02-12 10:58:38.656 INFO (MainThread) [homeassistant.components.automation.fire_alarm_cleared] Fire Alarm Cleared: Executing step call service
2024-02-12 10:58:41.575 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Queueing command '00' data: '' ; calling_task=keep_alive
2024-02-12 10:58:41.575 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Command processor woke up.
2024-02-12 10:58:41.575 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-02-12 10:58:41.575 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] TX > b'^00,$'
2024-02-12 10:58:41.576 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-02-12 10:58:41.578 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-02-12 10:58:41.578 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < ^00,00$

2024-02-12 10:58:41.578 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Data received:^00,00$
2024-02-12 10:58:41.578 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Code:^00 Data:00
2024-02-12 10:58:41.578 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_command_response for code: ^00 with data: 00
2024-02-12 10:58:41.578 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Envisalink response: Command Accepted
2024-02-12 10:58:41.578 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-02-12 10:58:41.578 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-02-12 10:58:41.578 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-02-12 10:58:41.578 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Command processor woke up.
2024-02-12 10:58:41.578 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-02-12 10:58:42.609 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-02-12 10:58:42.609 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < %00,01,1C08,08,00,****DISARMED****  Ready to Arm  $

2024-02-12 10:58:42.609 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Data received:%00,01,1C08,08,00,****DISARMED****  Ready to Arm  $
2024-02-12 10:58:42.609 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Code:%00 Data:01,1C08,08,00,****DISARMED****  Ready to Arm  
2024-02-12 10:58:42.609 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_keypad_update for code: %00 with data: 01,1C08,08,00,****DISARMED****  Ready to Arm  
2024-02-12 10:58:42.609 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] Keypad update is giving partition 1 status. Partition: ready Zonecode: unknown
2024-02-12 10:58:42.609 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.honeywell_client] {"partition_state": "N/A", "alpha": "****DISARMED****  Ready to Arm  ", "ac_present": true, "beep": "off", "armed_bypass": false, "entry_delay": false, "exit_delay": false, "last_armed_by_user": "", "last_disarmed_by_user": "", "ready": true, "bat_trouble": false, "trouble": false, "fire": false, "panic": false, "alarm": false, "alarm_fire_zone": false, "alarm_in_memory": false, "armed_away": false, "armed_stay": false, "armed_zero_entry_delay": false, "chime": false}
2024-02-12 10:58:42.609 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-02-12 10:58:42.609 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Triggering state change callback for partition: [1]
2024-02-12 10:58:42.609 DEBUG (MainThread) [custom_components.envisalink_new] The envisalink 'Alarm' sent a partition update event: [1]
2024-02-12 10:58:42.609 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm'
2024-02-12 10:58:42.609 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm AC Power'
2024-02-12 10:58:42.609 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Ready'
2024-02-12 10:58:42.609 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Panel Battery'
2024-02-12 10:58:42.609 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Panel Health'
2024-02-12 10:58:42.610 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Fire'
2024-02-12 10:58:42.610 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Alarm'
2024-02-12 10:58:42.610 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Alarm Keypad'
2024-02-12 10:58:42.610 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Panel Chime'
2024-02-12 10:58:42.610 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-02-12 10:58:42.610 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
@bdf0506
Copy link
Author

bdf0506 commented Feb 13, 2024

Also applies to *58 (expert zone program mode) and *82 (alpha descriptor programming), as well as probably plenty of other places in program mode.

@ufodone
Copy link
Owner

ufodone commented Feb 15, 2024

Zone updates are currently driven by the virtual keypad updates that the EVL sends. From the log it looks like entering program mode sends keypad updates that include zone information with the integration is likely interpreting as valid zone updates. I'm not sure yet whether I'll be able to filter those out based on the information available from the EVL.

But from what I can tell from the logs, while I see it triggering a state update, it doesn't look like it's actually changing the zone's state value. I see your automation triggering though so I'm curious what your triggers and conditions look like for the automations.

@bdf0506
Copy link
Author

bdf0506 commented Feb 15, 2024 •
edited
Loading

Here's a quick video of showing this. You can clearly see that going into program mode and going into zone 1 is changing the state of the binary sensor for zone 1. Same thing would happen if I went into program mode for another zone, the respective zone would also have a state change.

As far as the automations go, the triggers are very plain and boring. There are no conditions on the automation, just the triggers.

entity_id: binary_sensor.fire
platform: state
to: "on"

and

platform: state
entity_id: binary_sensor.fire
from: "on"
to: "off"
Untitled.video.-.Made.with.Clipchamp.2.mp4

@ufodone
Copy link
Owner

ufodone commented Feb 25, 2024

@philh30 Do you have any thoughts on this? It looks like not_used1 (bit 6) is set when in program mode and looking through other Honeywell logs from other issues, I haven't seen any other cases where this bit is set so maybe it could be used to filter detect this mode and prevent it from thinking these are zone updates?

@slaygirlz
Copy link

so off topic but what card did you use to control your panel like a keypad? that video

@bdf0506
Copy link
Author

bdf0506 commented Jun 22, 2024

so off topic but what card did you use to control your panel like a keypad? that video

https://community.home-assistant.io/t/honeywell-vista-20p-full-keypad-via-envisalink/690039/2

@ufodone
Copy link
Owner

ufodone commented Jun 22, 2024

@bdf0506 Just FYI, I haven't forgotten about this - I'm just not quite sure what to do about it. From my comment above, it might be possible to adjust the zone update handling but I'm a bit worried about it breaking other scenarios that are already working (there's a whole complicated history around this - some of which is detailed in #9).

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
3 participants
@bdf0506 @ufodone @slaygirlz