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.

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

DSC 4 EVL / Cannot Disarm #145

Open
cjoha opened this issue Oct 16, 2024 · 11 comments
Open

DSC 4 EVL / Cannot Disarm #145

cjoha opened this issue Oct 16, 2024 · 11 comments

Comments

@cjoha
Copy link

cjoha commented Oct 16, 2024

Scenario:
Everything is working well, having deployed this as a fresh install, I chose not to migrate over from the old integration. The system arms ok across all modes, and whilst I can disarm it whilst it is in the counting phase of arming. Once it is armed, I cannot disarm.

I checked the logs, and can see the event flagging the issue here:

2024-10-16 07:26:45.136 ERROR (MainThread) [custom_components.envisalink_new.pyenvisalink.dsc_client] System error received for issued command: API SystemNotArmed (sent in response to a disarm command) (023)

2024-10-16 07:27:04.200 ERROR (MainThread) [custom_components.envisalink_new.pyenvisalink.dsc_client] System error received for issued command: API SystemNotArmed (sent in response to a disarm command) (023)

2024-10-16 07:27:20.758 ERROR (MainThread) [custom_components.envisalink_new.pyenvisalink.dsc_client] System error received for issued command: API SystemNotArmed (sent in response to a disarm command) (023)

The timestamp for this matches when I was trying to disarm in HA UI. At that time, I confirmed the alarm is in an armed state, confirmed with keypads, and EyezOn Mobile app. Home Assistant also shows the armed state. I was able to disarm using the keypads, and I saw the state change in HA.

Versions:
Envisalink_new = 0.9.2
Core = 2024.10.2
Supervisor = 2024.10.2
Operating System = 13.2
Frontend = 20241002.3

@ufodone
Copy link
Owner

ufodone commented Oct 17, 2024

That's an error returned from the EVL device itself. Was this working with the stock integration? If so, could you enable debug logging and go through that arm/disarm sequence and then paste the logs?

What version of device is this and what firmware version are you running?

@cjoha
Copy link
Author

cjoha commented Oct 18, 2024

Interestingly, yes the original integration had the same issue. The card is an EnvisaLink 4, running Firmware Version: 01.05.213. Let me paste you the logs now.

@cjoha
Copy link
Author

cjoha commented Oct 18, 2024


2024-10-16 11:29:28.893 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_keypad_led_state_update for code: 510 with data: 8B
2024-10-16 11:29:28.893 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.dsc_client] Keypad LED state update: 0x8b ready=1 armed=1 memory=0 bypass=1 trouble=0 program=0 fire=0 backlight=1
2024-10-16 11:29:28.893 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-10-16 11:29:28.893 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Triggering state change callback for keypad: [1, 2, 3, 4, 5, 6, 7, 8]
2024-10-16 11:29:28.893 DEBUG (MainThread) [custom_components.envisalink_new] Envisalink sent 'Home Alarm' new alarm info. Updating alarms: [1, 2, 3, 4, 5, 6, 7, 8]
2024-10-16 11:29:28.893 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1'
2024-10-16 11:29:28.893 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 AC Power'
2024-10-16 11:29:28.894 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Ready'
2024-10-16 11:29:28.894 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Panel Battery'
2024-10-16 11:29:28.894 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Panel Health'
2024-10-16 11:29:28.894 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Fire'
2024-10-16 11:29:28.894 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Alarm'
2024-10-16 11:29:28.894 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Keypad'
2024-10-16 11:29:28.894 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Panel Chime'
2024-10-16 11:29:28.894 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-10-16 11:29:28.894 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:29:28.965 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-10-16 11:29:28.965 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < 6561D2

2024-10-16 11:29:28.965 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_partition_state_change for code: 656 with data: 1
2024-10-16 11:29:28.965 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.dsc_client] (partition 1) state has updated: {"alarm": false, "armed_stay": false, "armed_zero_entry_delay": false, "armed_away": false, "exit_delay": true, "entry_delay": false, "alpha": "Exit Delay In Progress"}
2024-10-16 11:29:28.965 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.dsc_client] New status for partition 1: {'partition_state': 'N/A', 'alpha': 'Exit Delay In Progress', 'ac_present': True, 'beep': False, 'armed_bypass': False, 'entry_delay': False, 'exit_delay': True, 'last_armed_by_user': 3, 'last_disarmed_by_user': 3, '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}
2024-10-16 11:29:28.965 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-10-16 11:29:28.965 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Triggering state change callback for partition: [1]
2024-10-16 11:29:28.965 DEBUG (MainThread) [custom_components.envisalink_new] The envisalink 'Home Alarm' sent a partition update event: [1]
2024-10-16 11:29:28.965 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1'
2024-10-16 11:29:28.966 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 AC Power'
2024-10-16 11:29:28.966 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Ready'
2024-10-16 11:29:28.966 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Panel Battery'
2024-10-16 11:29:28.966 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Panel Health'
2024-10-16 11:29:28.966 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Fire'
2024-10-16 11:29:28.966 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Alarm'
2024-10-16 11:29:28.966 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Keypad'
2024-10-16 11:29:28.966 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Panel Chime'
2024-10-16 11:29:28.966 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-10-16 11:29:28.966 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:29:33.967 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:29:38.967 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:29:51.422 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:29:56.424 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:30:01.425 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:30:06.426 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:30:08.505 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-10-16 11:30:08.506 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < 5108A0F

2024-10-16 11:30:08.506 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_keypad_led_state_update for code: 510 with data: 8A
2024-10-16 11:30:08.506 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.dsc_client] Keypad LED state update: 0x8a ready=0 armed=1 memory=0 bypass=1 trouble=0 program=0 fire=0 backlight=1
2024-10-16 11:30:08.507 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-10-16 11:30:08.507 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Triggering state change callback for keypad: [1, 2, 3, 4, 5, 6, 7, 8]
2024-10-16 11:30:08.507 DEBUG (MainThread) [custom_components.envisalink_new] Envisalink sent 'Home Alarm' new alarm info. Updating alarms: [1, 2, 3, 4, 5, 6, 7, 8]
2024-10-16 11:30:08.508 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1'
2024-10-16 11:30:08.508 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 AC Power'
2024-10-16 11:30:08.509 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Ready'
2024-10-16 11:30:08.509 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Panel Battery'
2024-10-16 11:30:08.510 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Panel Health'
2024-10-16 11:30:08.510 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Fire'
2024-10-16 11:30:08.510 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Alarm'
2024-10-16 11:30:08.511 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Keypad'
2024-10-16 11:30:08.521 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Panel Chime'
2024-10-16 11:30:08.521 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-10-16 11:30:08.522 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:30:09.043 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-10-16 11:30:09.044 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < 7011C9

2024-10-16 11:30:09.044 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.dsc_client] No handler defined in config for 701, skipping...
2024-10-16 11:30:09.045 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] No handler configured for evl command.
2024-10-16 11:30:09.045 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-10-16 11:30:09.045 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-10-16 11:30:09.045 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:30:09.166 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-10-16 11:30:09.167 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < 65211FF

2024-10-16 11:30:09.167 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_partition_state_change for code: 652 with data: 11
2024-10-16 11:30:09.168 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.dsc_client] (partition 1) state has updated: {"armed_away": false, "armed_stay": true, "armed_zero_entry_delay": false, "alpha": "Arm Stay", "exit_delay": false, "entry_delay": false}
2024-10-16 11:30:09.168 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-10-16 11:30:09.168 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Triggering state change callback for partition: [1]
2024-10-16 11:30:09.169 DEBUG (MainThread) [custom_components.envisalink_new] The envisalink 'Home Alarm' sent a partition update event: [1]
2024-10-16 11:30:09.169 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1'
2024-10-16 11:30:09.171 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 AC Power'
2024-10-16 11:30:09.172 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Ready'
2024-10-16 11:30:09.172 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Panel Battery'
2024-10-16 11:30:09.172 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Panel Health'
2024-10-16 11:30:09.173 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Fire'
2024-10-16 11:30:09.173 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Alarm'
2024-10-16 11:30:09.173 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Keypad'
2024-10-16 11:30:09.175 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Panel Chime'
2024-10-16 11:30:09.175 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-10-16 11:30:09.185 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:30:09.644 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Queueing command '000' data: '' ; calling_task=keep_alive
2024-10-16 11:30:09.645 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Command processor woke up.
2024-10-16 11:30:09.645 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-10-16 11:30:09.645 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] TX > b'00090'
2024-10-16 11:30:09.645 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-10-16 11:30:09.648 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-10-16 11:30:09.649 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < 50000025

2024-10-16 11:30:09.649 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_command_response for code: 500 with data: 000
2024-10-16 11:30:09.649 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.dsc_client] DSC ack recieved.
2024-10-16 11:30:09.649 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-10-16 11:30:09.649 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-10-16 11:30:09.649 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:30:09.650 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Command processor woke up.
2024-10-16 11:30:09.650 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-10-16 11:30:10.047 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-10-16 11:30:10.047 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < 60500934

2024-10-16 11:30:10.047 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_zone_state_change for code: 605 with data: 009
2024-10-16 11:30:10.048 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.dsc_client] (zone 9) state has updated: {"fault": true}
2024-10-16 11:30:10.048 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-10-16 11:30:10.048 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Triggering state change callback for zone: [9]
2024-10-16 11:30:10.048 DEBUG (MainThread) [custom_components.envisalink_new] Envisalink sent a 'Home Alarm' zone update event. Updating zones: [9]
2024-10-16 11:30:10.049 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Zone 9'
2024-10-16 11:30:10.051 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Zone 9 Fault'
2024-10-16 11:30:10.052 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-10-16 11:30:10.053 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:30:10.440 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-10-16 11:30:10.440 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < 60600935

2024-10-16 11:30:10.440 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_zone_state_change for code: 606 with data: 009
2024-10-16 11:30:10.440 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.dsc_client] (zone 9) state has updated: {"fault": false}
2024-10-16 11:30:10.441 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-10-16 11:30:10.441 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Triggering state change callback for zone: [9]
2024-10-16 11:30:10.441 DEBUG (MainThread) [custom_components.envisalink_new] Envisalink sent a 'Home Alarm' zone update event. Updating zones: [9]
2024-10-16 11:30:10.441 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Zone 9'
2024-10-16 11:30:10.442 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Zone 9 Fault'
2024-10-16 11:30:10.442 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-10-16 11:30:10.443 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:30:15.444 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:30:20.447 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:30:20.901 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-10-16 11:30:20.901 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < 6591D5

2024-10-16 11:30:20.902 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.dsc_client] No handler defined in config for 659, skipping...
2024-10-16 11:30:20.902 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] No handler configured for evl command.
2024-10-16 11:30:20.902 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-10-16 11:30:20.902 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-10-16 11:30:20.902 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:30:25.904 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:30:30.907 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:30:35.908 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:30:36.012 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Queueing command '040' data: '1****' ; calling_task=Task-351789
2024-10-16 11:30:36.012 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Command processor woke up.
2024-10-16 11:30:36.012 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-10-16 11:30:36.013 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] TX > b'0401****9A'
2024-10-16 11:30:36.015 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-10-16 11:30:36.016 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-10-16 11:30:36.016 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < 5020232C

2024-10-16 11:30:36.016 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_command_response for code: 502 with data: 023
2024-10-16 11:30:36.016 ERROR (MainThread) [custom_components.envisalink_new.pyenvisalink.dsc_client] System error received for issued command: API SystemNotArmed (sent in response to a disarm command) (023)
2024-10-16 11:30:36.017 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-10-16 11:30:36.017 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-10-16 11:30:36.017 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:30:36.017 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Command processor woke up.
2024-10-16 11:30:36.017 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-10-16 11:30:39.627 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Queueing command '040' data: '1****' ; calling_task=Task-351813
2024-10-16 11:30:39.628 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Command processor woke up.
2024-10-16 11:30:39.628 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-10-16 11:30:39.629 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] TX > b'0401****9A'
2024-10-16 11:30:39.629 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-10-16 11:30:39.632 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-10-16 11:30:39.633 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < 5020232C

2024-10-16 11:30:39.633 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_command_response for code: 502 with data: 023
2024-10-16 11:30:39.633 ERROR (MainThread) [custom_components.envisalink_new.pyenvisalink.dsc_client] System error received for issued command: API SystemNotArmed (sent in response to a disarm command) (023)
2024-10-16 11:30:39.633 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-10-16 11:30:39.633 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-10-16 11:30:39.634 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:30:39.634 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Command processor woke up.
2024-10-16 11:30:39.634 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-10-16 11:30:40.914 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Queueing command '040' data: '1****' ; calling_task=Task-351877
2024-10-16 11:30:40.915 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Command processor woke up.
2024-10-16 11:30:40.916 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-10-16 11:30:40.916 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] TX > b'0401****9A'
2024-10-16 11:30:40.917 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-10-16 11:30:40.923 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-10-16 11:30:40.923 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < 5020232C

2024-10-16 11:30:40.924 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_command_response for code: 502 with data: 023
2024-10-16 11:30:40.924 ERROR (MainThread) [custom_components.envisalink_new.pyenvisalink.dsc_client] System error received for issued command: API SystemNotArmed (sent in response to a disarm command) (023)
2024-10-16 11:30:40.924 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-10-16 11:30:40.925 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-10-16 11:30:40.925 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:30:40.926 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Command processor woke up.
2024-10-16 11:30:40.926 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-10-16 11:30:42.212 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Queueing command '040' data: '1****' ; calling_task=Task-351890
2024-10-16 11:30:42.213 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Command processor woke up.
2024-10-16 11:30:42.214 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-10-16 11:30:42.214 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] TX > b'0401****9A'
2024-10-16 11:30:42.215 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-10-16 11:30:42.221 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-10-16 11:30:42.221 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < 5020232C

2024-10-16 11:30:42.222 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_command_response for code: 502 with data: 023
2024-10-16 11:30:42.222 ERROR (MainThread) [custom_components.envisalink_new.pyenvisalink.dsc_client] System error received for issued command: API SystemNotArmed (sent in response to a disarm command) (023)
2024-10-16 11:30:42.222 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-10-16 11:30:42.223 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-10-16 11:30:42.223 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:30:42.224 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Command processor woke up.
2024-10-16 11:30:42.224 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-10-16 11:30:44.157 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Queueing command '040' data: '1****' ; calling_task=Task-351913
2024-10-16 11:30:44.159 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Command processor woke up.
2024-10-16 11:30:44.159 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-10-16 11:30:44.159 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] TX > b'0401****9A'
2024-10-16 11:30:44.160 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-10-16 11:30:44.165 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-10-16 11:30:44.165 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < 5020232C

2024-10-16 11:30:44.165 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_command_response for code: 502 with data: 023
2024-10-16 11:30:44.166 ERROR (MainThread) [custom_components.envisalink_new.pyenvisalink.dsc_client] System error received for issued command: API SystemNotArmed (sent in response to a disarm command) (023)
2024-10-16 11:30:44.166 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-10-16 11:30:44.166 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-10-16 11:30:44.166 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:30:44.167 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Command processor woke up.
2024-10-16 11:30:44.167 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-10-16 11:30:44.960 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Queueing command '040' data: '1****' ; calling_task=Task-351920
2024-10-16 11:30:44.961 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Command processor woke up.
2024-10-16 11:30:44.961 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-10-16 11:30:44.961 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] TX > b'0401****9A'
2024-10-16 11:30:44.962 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-10-16 11:30:44.965 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-10-16 11:30:44.965 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < 5020232C

2024-10-16 11:30:44.965 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_command_response for code: 502 with data: 023
2024-10-16 11:30:44.966 ERROR (MainThread) [custom_components.envisalink_new.pyenvisalink.dsc_client] System error received for issued command: API SystemNotArmed (sent in response to a disarm command) (023)
2024-10-16 11:30:44.966 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-10-16 11:30:44.966 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-10-16 11:30:44.966 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:30:44.967 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Command processor woke up.
2024-10-16 11:30:44.967 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-10-16 11:30:49.968 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:30:50.295 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Queueing command '040' data: '1****' ; calling_task=Task-351949
2024-10-16 11:30:50.296 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Command processor woke up.
2024-10-16 11:30:50.296 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-10-16 11:30:50.297 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] TX > b'0401****9A'
2024-10-16 11:30:50.297 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-10-16 11:30:50.304 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-10-16 11:30:50.304 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < 5020232C

2024-10-16 11:30:50.305 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_command_response for code: 502 with data: 023
2024-10-16 11:30:50.305 ERROR (MainThread) [custom_components.envisalink_new.pyenvisalink.dsc_client] System error received for issued command: API SystemNotArmed (sent in response to a disarm command) (023)
2024-10-16 11:30:50.305 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-10-16 11:30:50.305 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-10-16 11:30:50.306 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:30:50.306 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Command processor woke up.
2024-10-16 11:30:50.306 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-10-16 11:30:50.812 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Queueing command '040' data: '1****' ; calling_task=Task-351950
2024-10-16 11:30:50.813 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Command processor woke up.
2024-10-16 11:30:50.813 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-10-16 11:30:50.813 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] TX > b'0401****9A'
2024-10-16 11:30:50.814 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-10-16 11:30:50.816 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-10-16 11:30:50.816 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < 5020232C

2024-10-16 11:30:50.817 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_command_response for code: 502 with data: 023
2024-10-16 11:30:50.817 ERROR (MainThread) [custom_components.envisalink_new.pyenvisalink.dsc_client] System error received for issued command: API SystemNotArmed (sent in response to a disarm command) (023)
2024-10-16 11:30:50.817 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-10-16 11:30:50.817 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-10-16 11:30:50.817 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:30:50.817 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Command processor woke up.
2024-10-16 11:30:50.817 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-10-16 11:30:55.818 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:31:00.820 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:31:00.965 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-10-16 11:31:00.965 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < 65211FF

2024-10-16 11:31:00.965 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_partition_state_change for code: 652 with data: 11
2024-10-16 11:31:00.966 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.dsc_client] (partition 1) state has updated: {"armed_away": false, "armed_stay": true, "armed_zero_entry_delay": false, "alpha": "Arm Stay", "exit_delay": false, "entry_delay": false}
2024-10-16 11:31:00.966 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-10-16 11:31:00.966 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Triggering state change callback for partition: [1]
2024-10-16 11:31:00.966 DEBUG (MainThread) [custom_components.envisalink_new] The envisalink 'Home Alarm' sent a partition update event: [1]
2024-10-16 11:31:00.966 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1'
2024-10-16 11:31:00.966 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 AC Power'
2024-10-16 11:31:00.966 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Ready'
2024-10-16 11:31:00.967 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Panel Battery'
2024-10-16 11:31:00.967 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Panel Health'
2024-10-16 11:31:00.967 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Fire'
2024-10-16 11:31:00.967 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Alarm'
2024-10-16 11:31:00.967 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Keypad'
2024-10-16 11:31:00.967 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Panel Chime'
2024-10-16 11:31:00.968 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-10-16 11:31:00.968 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:31:01.965 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-10-16 11:31:01.965 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < 51081FF

2024-10-16 11:31:01.965 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_keypad_led_state_update for code: 510 with data: 81
2024-10-16 11:31:01.965 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.dsc_client] Keypad LED state update: 0x81 ready=1 armed=0 memory=0 bypass=0 trouble=0 program=0 fire=0 backlight=1
2024-10-16 11:31:01.965 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-10-16 11:31:01.965 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Triggering state change callback for keypad: [1, 2, 3, 4, 5, 6, 7, 8]
2024-10-16 11:31:01.965 DEBUG (MainThread) [custom_components.envisalink_new] Envisalink sent 'Home Alarm' new alarm info. Updating alarms: [1, 2, 3, 4, 5, 6, 7, 8]
2024-10-16 11:31:01.965 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1'
2024-10-16 11:31:01.965 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 AC Power'
2024-10-16 11:31:01.965 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Ready'
2024-10-16 11:31:01.965 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Panel Battery'
2024-10-16 11:31:01.965 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Panel Health'
2024-10-16 11:31:01.965 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Fire'
2024-10-16 11:31:01.965 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Alarm'
2024-10-16 11:31:01.965 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Keypad'
2024-10-16 11:31:01.965 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Panel Chime'
2024-10-16 11:31:01.965 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-10-16 11:31:01.965 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:31:02.152 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-10-16 11:31:02.152 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < 750100018E

2024-10-16 11:31:02.152 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_partition_state_change for code: 750 with data: 10001
2024-10-16 11:31:02.153 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.dsc_client] (partition 1) state has updated: {"alarm": false, "armed_stay": false, "armed_away": false, "armed_zero_entry_delay": false, "exit_delay": false, "entry_delay": false, "alpha": "Disarmed"}
2024-10-16 11:31:02.153 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.dsc_client] New status for partition 1: {'partition_state': 'N/A', 'alpha': 'Disarmed', 'ac_present': True, 'beep': False, 'armed_bypass': False, 'entry_delay': False, 'exit_delay': False, 'last_armed_by_user': 3, 'last_disarmed_by_user': 1, '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}
2024-10-16 11:31:02.153 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-10-16 11:31:02.153 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Triggering state change callback for partition: [1]
2024-10-16 11:31:02.153 DEBUG (MainThread) [custom_components.envisalink_new] The envisalink 'Home Alarm' sent a partition update event: [1]
2024-10-16 11:31:02.153 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1'
2024-10-16 11:31:02.153 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 AC Power'
2024-10-16 11:31:02.153 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Ready'
2024-10-16 11:31:02.153 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Panel Battery'
2024-10-16 11:31:02.153 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Panel Health'
2024-10-16 11:31:02.154 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Fire'
2024-10-16 11:31:02.154 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Alarm'
2024-10-16 11:31:02.154 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Keypad'
2024-10-16 11:31:02.154 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Panel Chime'
2024-10-16 11:31:02.154 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-10-16 11:31:02.154 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:31:02.154 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-10-16 11:31:02.154 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < 6551D1

2024-10-16 11:31:02.154 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_partition_state_change for code: 655 with data: 1
2024-10-16 11:31:02.154 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.dsc_client] (partition 1) state has updated: {"alarm": false, "armed_stay": false, "armed_zero_entry_delay": false, "armed_away": false, "exit_delay": false, "entry_delay": false, "fire": false, "panic": false, "alpha": "Disarmed"}
2024-10-16 11:31:02.154 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.dsc_client] New status for partition 1: {'partition_state': 'N/A', 'alpha': 'Disarmed', 'ac_present': True, 'beep': False, 'armed_bypass': False, 'entry_delay': False, 'exit_delay': False, 'last_armed_by_user': 3, 'last_disarmed_by_user': 1, '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}
2024-10-16 11:31:02.154 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-10-16 11:31:02.154 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Triggering state change callback for partition: [1]
2024-10-16 11:31:02.154 DEBUG (MainThread) [custom_components.envisalink_new] The envisalink 'Home Alarm' sent a partition update event: [1]
2024-10-16 11:31:02.154 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1'
2024-10-16 11:31:02.154 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 AC Power'
2024-10-16 11:31:02.154 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Ready'
2024-10-16 11:31:02.154 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Panel Battery'
2024-10-16 11:31:02.155 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Panel Health'
2024-10-16 11:31:02.155 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Fire'
2024-10-16 11:31:02.155 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Alarm'
2024-10-16 11:31:02.155 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Keypad'
2024-10-16 11:31:02.155 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Panel Chime'
2024-10-16 11:31:02.155 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-10-16 11:31:02.155 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:31:05.963 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-10-16 11:31:05.964 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < 6501CC

2024-10-16 11:31:05.964 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_partition_state_change for code: 650 with data: 1
2024-10-16 11:31:05.964 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.dsc_client] (partition 1) state has updated: {"ready": true, "alarm": false, "armed_stay": false, "armed_zero_entry_delay": false, "armed_away": false, "exit_delay": false, "entry_delay": false, "fire": false, "panic": false, "alpha": "Ready"}
2024-10-16 11:31:05.964 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.dsc_client] New status for partition 1: {'partition_state': 'N/A', 'alpha': 'Ready', 'ac_present': True, 'beep': False, 'armed_bypass': False, 'entry_delay': False, 'exit_delay': False, 'last_armed_by_user': 3, 'last_disarmed_by_user': 1, '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}
2024-10-16 11:31:05.964 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-10-16 11:31:05.965 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Triggering state change callback for partition: [1]
2024-10-16 11:31:05.965 DEBUG (MainThread) [custom_components.envisalink_new] The envisalink 'Home Alarm' sent a partition update event: [1]
2024-10-16 11:31:05.965 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1'
2024-10-16 11:31:05.965 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 AC Power'
2024-10-16 11:31:05.965 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Ready'
2024-10-16 11:31:05.966 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Panel Battery'
2024-10-16 11:31:05.966 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Panel Health'
2024-10-16 11:31:05.966 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Fire'
2024-10-16 11:31:05.966 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Alarm'
2024-10-16 11:31:05.967 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Partition 1 Keypad'
2024-10-16 11:31:05.968 DEBUG (MainThread) [custom_components.envisalink_new] state_updated for 'Panel Chime'
2024-10-16 11:31:05.968 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-10-16 11:31:05.968 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:31:09.644 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Queueing command '000' data: '' ; calling_task=keep_alive
2024-10-16 11:31:09.644 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Command processor woke up.
2024-10-16 11:31:09.644 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-10-16 11:31:09.645 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] TX > b'00090'
2024-10-16 11:31:09.645 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1
2024-10-16 11:31:09.646 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] {---------------------------------------
2024-10-16 11:31:09.647 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] RX < 50000025

2024-10-16 11:31:09.647 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] calling handler: handle_command_response for code: 500 with data: 000
2024-10-16 11:31:09.647 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.dsc_client] DSC ack recieved.
2024-10-16 11:31:09.647 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Invoking state change callbacks
2024-10-16 11:31:09.647 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] }---------------------------------------
2024-10-16 11:31:09.647 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Waiting for data from EVL
2024-10-16 11:31:09.647 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Command processor woke up.
2024-10-16 11:31:09.647 DEBUG (MainThread) [custom_components.envisalink_new.pyenvisalink.envisalink_base_client] Checking command queue: len=1

@ufodone
Copy link
Owner

ufodone commented Oct 21, 2024

Do you have "keypad blanking" setup on your panel? I found an old EyezOn forum thread which sounds like a very similar issue to what you are seeing.

@cjoha
Copy link
Author

cjoha commented Oct 21, 2024 via email

@BobRobertBob
Copy link

Short answer: Section 16, Fourth System Option Code, Option 3 should be OFF (zero) to disable Keypad Blanking.
image
If that doesn't mean anything to you, then the long answer is you need the "Installers Code" and the Installation Manual. Read up on programming the panel. It's a little tedious but not bad once you get the hang of it. Easier with a LCD keypad.

@BobRobertBob
Copy link

If you need more help, let me know.

@cjoha
Copy link
Author

cjoha commented Oct 22, 2024

So I've just entered the installer code, then 016 to get to the configuration options. Using the < > keys on the keypad I moved to the Keypad Blanking section. It actually shows as off. When I arm overnight, the screen goes off, but the keys remain backlit. Does that sound like keypad blanking is on???

@BobRobertBob
Copy link

I have the PK5500 keypads and this is what I see at 016:
20241022_165651560_iOS
So in my case, AC Trouble Display in ON, Keypad Backlighting is ON, and Power Save Mode is ON. Keypad blanking (3 and 4) is OFF.
Regarding the keypad screen, mine never goes off but the back lighting (screen and keys) goes off after about 45 seconds. For the keypad in our bedroom, I have the AC Power Indicator OFF as it is very bright at night (Keypad Program, Section 77). I believe this is specific to the PK5500 keyboard. Hope this is helpful.

@cjoha
Copy link
Author

cjoha commented Oct 24, 2024

Interesting. This is my keypad state when armed. Is this different to yours?
IMG_1019

@BobRobertBob
Copy link

Completely different. You have a blank screen but with backlighting, never seen that before, Also notice that the armed LED lock symbol is off.
Here is what mine looks like when armed:
20241024_112508111_iOS
My only suggestion would to check your configuration again at 016. If its okay then have a look through the manual to see if any other sections would impact this.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

3 participants