Open redneckdba opened 2 months ago
pawlizio
commented
2 months ago On first setup it will reboot indeed as it connects and disconnects with klf200 during configuration workflow. On disconnect pyvlx now automatically triggers a reboot to avoid unresponsive KLF200.
Just ask myself why this fail the setup, especially why several reboot are triggered.
Do you have a velux entry in your configuration.yaml?
redneckdba
commented
2 months ago I migrated to your custom component already 1+years ago and got rid of any manual config entries once the workflow was available. I can hook up my test environment to the box as well and do some more testing if that helps in any way.
pawlizio
commented
2 months ago Probably it is linked to another issue which I was working on. You could try the pre-release 2.9.13, which links to a modified pyvlx library. At least I was able to reproduce it and this modification seems to fix it.
redneckdba
commented
2 months ago Wow! Thanks for your fast response and fix! Just updated my test environment to 2.9.13 and I was immediately able to configure the gateway without causing the reboot to abort the workflow.
2024-05-25 21:59:40.921 DEBUG (MainThread) [pyvlx] Heartbeat __init__ 2024-05-25 21:59:40.921 DEBUG (MainThread) [pyvlx] Loadig pyvlx v0.1.103 2024-05-25 21:59:40.921 DEBUG (MainThread) [pyvlx] Connecting to KLF 200 2024-05-25 21:59:45.076 DEBUG (MainThread) [pyvlx] Socket connection to KLF 200 opened 2024-05-25 21:59:45.076 DEBUG (MainThread) [pyvlx] Amount of connections since last HA start: 1 2024-05-25 21:59:45.077 DEBUG (MainThread) [pyvlx] SEND: <FramePasswordEnterRequest password="******"/> 2024-05-25 21:59:45.083 DEBUG (MainThread) [pyvlx] REC: <FramePasswordEnterConfirmation status="PasswordEnterConfirmationStatus.SUCCESSFUL"/> 2024-05-25 21:59:45.084 DEBUG (MainThread) [pyvlx] SEND: <FrameGetVersionRequest/> 2024-05-25 21:59:45.087 DEBUG (MainThread) [pyvlx] REC: <FrameGetVersionConfirmation software_version="0.2.0.0.71.0" hardware_version="6" product="KLF 200"/> 2024-05-25 21:59:45.088 DEBUG (MainThread) [pyvlx] SEND: <FrameGetProtocolVersionRequest/> 2024-05-25 21:59:45.296 DEBUG (MainThread) [pyvlx] REC: <FrameGetProtocolVersionConfirmation version="3.14"/> 2024-05-25 21:59:45.297 DEBUG (MainThread) [pyvlx] Connected to: <DtoVersion softwareversion="0.2.0.0.71.0" hardwareversion="6" productgroup="14" producttype="3"/>, <DtoProtocolVersion majorversion="3" minorversion="14"/> 2024-05-25 21:59:45.297 DEBUG (MainThread) [pyvlx] SEND: <FrameHouseStatusMonitorDisableRequest/> 2024-05-25 21:59:46.063 DEBUG (MainThread) [pyvlx] REC: <FrameHouseStatusMonitorDisableConfirmation/> 2024-05-25 21:59:46.063 DEBUG (MainThread) [pyvlx] SEND: <FrameGetStateRequest/> 2024-05-25 21:59:46.274 DEBUG (MainThread) [pyvlx] REC: <FrameGetStateConfirmation gateway_state="GatewayState.GATEWAY_MODE_WITH_ACTUATORS" gateway_sub_state="GatewaySubState.IDLE"/> 2024-05-25 21:59:46.275 DEBUG (MainThread) [pyvlx] SEND: <FrameSetUTCRequest time="2024-05-25 21:59:24"/> 2024-05-25 21:59:46.484 DEBUG (MainThread) [pyvlx] REC: <FrameSetUTCConfirmation/> 2024-05-25 21:59:46.485 DEBUG (MainThread) [pyvlx] SEND: <FrameGetNetworkSetupRequest/> 2024-05-25 21:59:46.489 DEBUG (MainThread) [pyvlx] REC: <FrameGetNetworkSetupConfirmation ipaddress="10.111.10.36" netmask="255.255.255.0" gateway="10.111.10.1" dhcp="DHCPParameter.DISABLE"/> 2024-05-25 21:59:46.489 DEBUG (MainThread) [pyvlx] SEND: <FrameHouseStatusMonitorEnableRequest/> 2024-05-25 21:59:47.707 DEBUG (MainThread) [pyvlx] REC: <FrameHouseStatusMonitorEnableConfirmation/> 2024-05-25 21:59:47.709 DEBUG (MainThread) [pyvlx] Heartbeat start 2024-05-25 21:59:47.709 DEBUG (MainThread) [pyvlx] Heartbeat was not running 2024-05-25 21:59:47.709 DEBUG (MainThread) [pyvlx] SEND: <FrameHouseStatusMonitorDisableRequest/> 2024-05-25 21:59:47.710 DEBUG (MainThread) [pyvlx] Heartbeat: creating task 2024-05-25 21:59:47.710 DEBUG (MainThread) [pyvlx] Heartbeat: task started 2024-05-25 21:59:47.710 DEBUG (MainThread) [pyvlx] Heartbeat: sleeping 2024-05-25 21:59:48.712 DEBUG (MainThread) [pyvlx] TCP transport closed. 2024-05-25 21:59:48.712 DEBUG (MainThread) [pyvlx] Heartbeat __init__ 2024-05-25 21:59:48.712 DEBUG (MainThread) [pyvlx] Loadig pyvlx v0.1.103 2024-05-25 21:59:48.712 DEBUG (MainThread) [pyvlx] Connecting to KLF 200 2024-05-25 21:59:48.729 WARNING (MainThread) [custom_components.velux] Unable to connect to KLF200: [Errno 111] Connect call failed ('10.111.10.36', 51200) 2024-05-25 21:59:48.745 DEBUG (MainThread) [pyvlx] Socket connection to KLF 200 has been lost 2024-05-25 21:59:48.745 DEBUG (MainThread) [pyvlx] TCP transport closed. 2024-05-25 21:59:53.915 DEBUG (MainThread) [pyvlx] Heartbeat __init__ 2024-05-25 21:59:53.915 DEBUG (MainThread) [pyvlx] Loadig pyvlx v0.1.103 2024-05-25 21:59:53.915 DEBUG (MainThread) [pyvlx] Connecting to KLF 200 2024-05-25 21:59:58.071 DEBUG (MainThread) [pyvlx] Socket connection to KLF 200 opened 2024-05-25 21:59:58.072 DEBUG (MainThread) [pyvlx] Amount of connections since last HA start: 1 2024-05-25 21:59:58.072 DEBUG (MainThread) [pyvlx] SEND: <FramePasswordEnterRequest password="******"/> 2024-05-25 21:59:58.079 DEBUG (MainThread) [pyvlx] REC: <FramePasswordEnterConfirmation status="PasswordEnterConfirmationStatus.SUCCESSFUL"/> 2024-05-25 21:59:58.079 DEBUG (MainThread) [pyvlx] SEND: <FrameGetVersionRequest/> 2024-05-25 21:59:58.083 DEBUG (MainThread) [pyvlx] REC: <FrameGetVersionConfirmation software_version="0.2.0.0.71.0" hardware_version="6" product="KLF 200"/> 2024-05-25 21:59:58.083 DEBUG (MainThread) [pyvlx] SEND: <FrameGetProtocolVersionRequest/> 2024-05-25 21:59:58.293 DEBUG (MainThread) [pyvlx] REC: <FrameGetProtocolVersionConfirmation version="3.14"/> 2024-05-25 21:59:58.294 DEBUG (MainThread) [pyvlx] Connected to: <DtoVersion softwareversion="0.2.0.0.71.0" hardwareversion="6" productgroup="14" producttype="3"/>, <DtoProtocolVersion majorversion="3" minorversion="14"/> 2024-05-25 21:59:58.294 DEBUG (MainThread) [pyvlx] SEND: <FrameHouseStatusMonitorDisableRequest/> 2024-05-25 21:59:58.510 DEBUG (MainThread) [pyvlx] REC: <FrameHouseStatusMonitorDisableConfirmation/> 2024-05-25 21:59:58.510 DEBUG (MainThread) [pyvlx] SEND: <FrameGetStateRequest/> 2024-05-25 21:59:58.720 DEBUG (MainThread) [pyvlx] REC: <FrameGetStateConfirmation gateway_state="GatewayState.GATEWAY_MODE_WITH_ACTUATORS" gateway_sub_state="GatewaySubState.IDLE"/> 2024-05-25 21:59:58.720 DEBUG (MainThread) [pyvlx] SEND: <FrameSetUTCRequest time="2024-05-25 21:59:24"/> 2024-05-25 21:59:58.931 DEBUG (MainThread) [pyvlx] REC: <FrameSetUTCConfirmation/> 2024-05-25 21:59:58.931 DEBUG (MainThread) [pyvlx] SEND: <FrameGetNetworkSetupRequest/> 2024-05-25 21:59:58.935 DEBUG (MainThread) [pyvlx] REC: <FrameGetNetworkSetupConfirmation ipaddress="10.111.10.36" netmask="255.255.255.0" gateway="10.111.10.1" dhcp="DHCPParameter.DISABLE"/> 2024-05-25 21:59:58.935 DEBUG (MainThread) [pyvlx] SEND: <FrameHouseStatusMonitorEnableRequest/> 2024-05-25 22:00:00.113 DEBUG (MainThread) [pyvlx] REC: <FrameHouseStatusMonitorEnableConfirmation/> 2024-05-25 22:00:00.113 DEBUG (MainThread) [pyvlx] Heartbeat start 2024-05-25 22:00:00.115 DEBUG (MainThread) [pyvlx] TCP transport closed. 2024-05-25 22:00:00.114 DEBUG (MainThread) [pyvlx] SEND: <FrameGetAllNodesInformationRequest/> 2024-05-25 22:00:00.116 DEBUG (MainThread) [pyvlx] Heartbeat: creating task 2024-05-25 22:00:00.116 DEBUG (MainThread) [pyvlx] Heartbeat: task started 2024-05-25 22:00:00.116 DEBUG (MainThread) [pyvlx] Heartbeat: sleeping 2024-05-25 22:00:00.598 DEBUG (MainThread) [pyvlx] REC: <FrameGetAllNodesInformationConfirmation status="AllNodesInformationStatus.OK" number_of_nodes="9"/>
pawlizio
commented
2 months ago I think in the background the klf200 is still rebooting during configuration workflow, it just don't lead to an issue. Official pyvlx has some fire & forget commands which seems to cause this issue.
After updating the custom component from 2.9.8 to 2.9.12, I had HA discover the same KLF200 as new device so I went ahead and removed the exisiting integration. Upon rediscovery I had the gateway reboot immediately causing the discovery workflow to fail. After downgrading, discovery works again as expected.
Debug log with 2.9.12
2024-05-25 11:46:47.092 DEBUG (MainThread) [pyvlx] Heartbeat __init__ 2024-05-25 11:46:47.093 DEBUG (MainThread) [pyvlx] Loadig pyvlx v0.2.23 2024-05-25 11:46:47.093 DEBUG (MainThread) [pyvlx] Connecting to KLF 200 2024-05-25 11:46:47.153 DEBUG (MainThread) [pyvlx] Connecting to KLF 200 was closed 2024-05-25 11:46:51.829 DEBUG (MainThread) [pyvlx] Amount of connections since last HA start: 1 2024-05-25 11:46:51.829 DEBUG (MainThread) [pyvlx] SEND: <FramePasswordEnterRequest password="******"/> 2024-05-25 11:46:51.839 DEBUG (MainThread) [pyvlx] REC: <FramePasswordEnterConfirmation status="PasswordEnterConfirmationStatus.SUCCESSFUL"/> 2024-05-25 11:46:51.843 DEBUG (MainThread) [pyvlx] SEND: <FrameGetVersionRequest/> 2024-05-25 11:46:51.848 DEBUG (MainThread) [pyvlx] REC: <FrameGetVersionConfirmation software_version="0.2.0.0.71.0" hardware_version="6" product="KLF 200"/> 2024-05-25 11:46:51.851 DEBUG (MainThread) [pyvlx] SEND: <FrameGetProtocolVersionRequest/> 2024-05-25 11:46:52.049 DEBUG (MainThread) [pyvlx] REC: <FrameGetProtocolVersionConfirmation version="3.14"/> 2024-05-25 11:46:52.053 DEBUG (MainThread) [pyvlx] Connected to: <DtoVersion softwareversion="0.2.0.0.71.0" hardwareversion="6" productgroup="14" producttype="3"/>, <DtoProtocolVersion majorversion="3" minorversion="14"/> 2024-05-25 11:46:52.053 DEBUG (MainThread) [pyvlx] SEND: <FrameHouseStatusMonitorDisableRequest/> 2024-05-25 11:46:52.267 DEBUG (MainThread) [pyvlx] REC: <FrameHouseStatusMonitorDisableConfirmation/> 2024-05-25 11:46:52.272 DEBUG (MainThread) [pyvlx] SEND: <FrameGetStateRequest/> 2024-05-25 11:46:52.478 DEBUG (MainThread) [pyvlx] REC: <FrameGetStateConfirmation gateway_state="GatewayState.GATEWAY_MODE_WITH_ACTUATORS" gateway_sub_state="GatewaySubState.IDLE"/> 2024-05-25 11:46:52.483 DEBUG (MainThread) [pyvlx] SEND: <FrameSetUTCRequest time="2024-05-25 11:45:56"/> 2024-05-25 11:46:52.688 DEBUG (MainThread) [pyvlx] REC: <FrameSetUTCConfirmation/> 2024-05-25 11:46:52.693 DEBUG (MainThread) [pyvlx] SEND: <FrameGetNetworkSetupRequest/> 2024-05-25 11:46:52.700 DEBUG (MainThread) [pyvlx] REC: <FrameGetNetworkSetupConfirmation ipaddress="10.111.10.36" netmask="255.255.255.0" gateway="10.111.10.1" dhcp="DHCPParameter.DISABLE"/> 2024-05-25 11:46:52.703 DEBUG (MainThread) [pyvlx] SEND: <FrameHouseStatusMonitorEnableRequest/> 2024-05-25 11:46:53.760 DEBUG (MainThread) [pyvlx] REC: <FrameHouseStatusMonitorEnableConfirmation/> 2024-05-25 11:46:53.765 DEBUG (MainThread) [pyvlx] Heartbeat start 2024-05-25 11:46:53.766 DEBUG (MainThread) [pyvlx] Connecting to KLF 200 was opened 2024-05-25 11:46:53.766 DEBUG (MainThread) [pyvlx] SEND: <FrameHouseStatusMonitorDisableRequest/> 2024-05-25 11:46:53.768 DEBUG (MainThread) [pyvlx] Heartbeat: creating task 2024-05-25 11:46:53.768 DEBUG (MainThread) [pyvlx] Heartbeat: task started 2024-05-25 11:46:53.768 DEBUG (MainThread) [pyvlx] Heartbeat: sleeping 2024-05-25 11:46:54.769 DEBUG (MainThread) [pyvlx] Heartbeat stopped 2024-05-25 11:46:54.769 DEBUG (MainThread) [pyvlx] SEND: <FrameGatewayRebootRequest/> 2024-05-25 11:46:54.915 DEBUG (MainThread) [pyvlx] REC: <FrameHouseStatusMonitorDisableConfirmation/> 2024-05-25 11:46:55.125 DEBUG (MainThread) [pyvlx] REC: <FrameGatewayRebootConfirmation/> 2024-05-25 11:46:55.130 WARNING (MainThread) [pyvlx] KLF200 is rebooting 2024-05-25 11:46:55.131 DEBUG (MainThread) [pyvlx] SEND: <FrameHouseStatusMonitorDisableRequest/> 2024-05-25 11:46:56.134 DEBUG (MainThread) [pyvlx] Heartbeat was not running 2024-05-25 11:46:56.134 DEBUG (MainThread) [pyvlx] SEND: <FrameGatewayRebootRequest/> 2024-05-25 11:47:04.228 DEBUG (MainThread) [pyvlx] Connecting to KLF 200 was closed 2024-05-25 11:47:06.137 DEBUG (MainThread) [custom_components.velux] Cannot connect: <PyVLXException description="Unable to reboot gateway." /> 2024-05-25 11:47:19.326 DEBUG (MainThread) [pyvlx] Heartbeat __init__ 2024-05-25 11:47:19.327 DEBUG (MainThread) [pyvlx] Loadig pyvlx v0.2.23 2024-05-25 11:47:19.327 DEBUG (MainThread) [pyvlx] Connecting to KLF 200 2024-05-25 11:47:19.384 DEBUG (MainThread) [pyvlx] Connecting to KLF 200 was closed 2024-05-25 11:47:24.050 DEBUG (MainThread) [pyvlx] Amount of connections since last HA start: 1 2024-05-25 11:47:24.051 DEBUG (MainThread) [pyvlx] SEND: <FramePasswordEnterRequest password="******"/> 2024-05-25 11:47:24.055 DEBUG (MainThread) [pyvlx] REC: <FramePasswordEnterConfirmation status="PasswordEnterConfirmationStatus.SUCCESSFUL"/> 2024-05-25 11:47:24.057 DEBUG (MainThread) [pyvlx] SEND: <FrameGetVersionRequest/> 2024-05-25 11:47:24.060 DEBUG (MainThread) [pyvlx] REC: <FrameGetVersionConfirmation software_version="0.2.0.0.71.0" hardware_version="6" product="KLF 200"/> 2024-05-25 11:47:24.062 DEBUG (MainThread) [pyvlx] SEND: <FrameGetProtocolVersionRequest/> 2024-05-25 11:47:24.261 DEBUG (MainThread) [pyvlx] REC: <FrameGetProtocolVersionConfirmation version="3.14"/> 2024-05-25 11:47:24.262 DEBUG (MainThread) [pyvlx] Connected to: <DtoVersion softwareversion="0.2.0.0.71.0" hardwareversion="6" productgroup="14" producttype="3"/>, <DtoProtocolVersion majorversion="3" minorversion="14"/> 2024-05-25 11:47:24.263 DEBUG (MainThread) [pyvlx] SEND: <FrameHouseStatusMonitorDisableRequest/> 2024-05-25 11:47:24.479 DEBUG (MainThread) [pyvlx] REC: <FrameHouseStatusMonitorDisableConfirmation/> 2024-05-25 11:47:24.480 DEBUG (MainThread) [pyvlx] SEND: <FrameGetStateRequest/> 2024-05-25 11:47:24.689 DEBUG (MainThread) [pyvlx] REC: <FrameGetStateConfirmation gateway_state="GatewayState.GATEWAY_MODE_WITH_ACTUATORS" gateway_sub_state="GatewaySubState.IDLE"/> 2024-05-25 11:47:24.690 DEBUG (MainThread) [pyvlx] SEND: <FrameSetUTCRequest time="2024-05-25 11:45:56"/> 2024-05-25 11:47:24.900 DEBUG (MainThread) [pyvlx] REC: <FrameSetUTCConfirmation/> 2024-05-25 11:47:24.901 DEBUG (MainThread) [pyvlx] SEND: <FrameGetNetworkSetupRequest/> 2024-05-25 11:47:24.905 DEBUG (MainThread) [pyvlx] REC: <FrameGetNetworkSetupConfirmation ipaddress="10.111.10.36" netmask="255.255.255.0" gateway="10.111.10.1" dhcp="DHCPParameter.DISABLE"/> 2024-05-25 11:47:24.906 DEBUG (MainThread) [pyvlx] SEND: <FrameHouseStatusMonitorEnableRequest/> 2024-05-25 11:47:25.959 DEBUG (MainThread) [pyvlx] REC: <FrameHouseStatusMonitorEnableConfirmation/> 2024-05-25 11:47:25.960 DEBUG (MainThread) [pyvlx] Heartbeat start 2024-05-25 11:47:25.962 DEBUG (MainThread) [pyvlx] Connecting to KLF 200 was opened 2024-05-25 11:47:25.962 DEBUG (MainThread) [pyvlx] SEND: <FrameHouseStatusMonitorDisableRequest/> 2024-05-25 11:47:25.966 DEBUG (MainThread) [pyvlx] Heartbeat: creating task 2024-05-25 11:47:25.967 DEBUG (MainThread) [pyvlx] Heartbeat: task started 2024-05-25 11:47:25.967 DEBUG (MainThread) [pyvlx] Heartbeat: sleeping 2024-05-25 11:47:26.965 DEBUG (MainThread) [pyvlx] Heartbeat stopped 2024-05-25 11:47:26.966 DEBUG (MainThread) [pyvlx] SEND: <FrameGatewayRebootRequest/> 2024-05-25 11:47:27.124 DEBUG (MainThread) [pyvlx] REC: <FrameHouseStatusMonitorDisableConfirmation/> 2024-05-25 11:47:27.334 DEBUG (MainThread) [pyvlx] REC: <FrameGatewayRebootConfirmation/> 2024-05-25 11:47:27.335 WARNING (MainThread) [pyvlx] KLF200 is rebooting 2024-05-25 11:47:27.336 DEBUG (MainThread) [pyvlx] SEND: <FrameHouseStatusMonitorDisableRequest/> 2024-05-25 11:47:28.340 DEBUG (MainThread) [pyvlx] Heartbeat was not running 2024-05-25 11:47:28.341 DEBUG (MainThread) [pyvlx] SEND: <FrameGatewayRebootRequest/> 2024-05-25 11:47:36.481 DEBUG (MainThread) [pyvlx] Connecting to KLF 200 was closed 2024-05-25 11:47:38.345 DEBUG (MainThread) [custom_components.velux] Cannot connect: <PyVLXException description="Unable to reboot gateway." />Debug Log from 2.9.8:
2024-05-25 11:58:56.453 DEBUG (MainThread) [pyvlx] Heartbeat __init__ 2024-05-25 11:58:56.457 DEBUG (MainThread) [pyvlx] Loadig pyvlx v0.1.76 2024-05-25 11:58:56.457 DEBUG (MainThread) [pyvlx] Connecting to KLF 200 2024-05-25 11:58:56.584 DEBUG (MainThread) [pyvlx] Connecting to KLF 200 was closed 2024-05-25 11:59:01.255 DEBUG (MainThread) [pyvlx] Amount of connections since last HA start: 1 2024-05-25 11:59:01.260 DEBUG (MainThread) [pyvlx] SEND: <FramePasswordEnterRequest password="******"/> 2024-05-25 11:59:01.267 DEBUG (MainThread) [pyvlx] REC: <FramePasswordEnterConfirmation status="PasswordEnterConfirmationStatus.SUCCESSFUL"/> 2024-05-25 11:59:01.270 DEBUG (MainThread) [pyvlx] SEND: <FrameGetVersionRequest/> 2024-05-25 11:59:01.275 DEBUG (MainThread) [pyvlx] REC: <FrameGetVersionConfirmation software_version="0.2.0.0.71.0" hardware_version="6" product="KLF 200"/> 2024-05-25 11:59:01.278 DEBUG (MainThread) [pyvlx] SEND: <FrameGetProtocolVersionRequest/> 2024-05-25 11:59:01.477 DEBUG (MainThread) [pyvlx] REC: <FrameGetProtocolVersionConfirmation version="3.14"/> 2024-05-25 11:59:01.482 DEBUG (MainThread) [pyvlx] Connected to: <DtoVersion softwareversion="0.2.0.0.71.0" hardwareversion="6" productgroup="14" producttype="3"/>, <DtoProtocolVersion majorversion="3" minorversion="14"/> 2024-05-25 11:59:01.482 DEBUG (MainThread) [pyvlx] SEND: <FrameHouseStatusMonitorDisableRequest/> 2024-05-25 11:59:01.695 DEBUG (MainThread) [pyvlx] REC: <FrameHouseStatusMonitorDisableConfirmation/> 2024-05-25 11:59:01.700 DEBUG (MainThread) [pyvlx] SEND: <FrameGetStateRequest/> 2024-05-25 11:59:01.905 DEBUG (MainThread) [pyvlx] REC: <FrameGetStateConfirmation gateway_state="GatewayState.GATEWAY_MODE_WITH_ACTUATORS" gateway_sub_state="GatewaySubState.IDLE"/> 2024-05-25 11:59:01.918 DEBUG (MainThread) [pyvlx] SEND: <FrameSetUTCRequest time="2024-05-25 11:58:17"/> 2024-05-25 11:59:02.116 DEBUG (MainThread) [pyvlx] REC: <FrameSetUTCConfirmation/> 2024-05-25 11:59:02.120 DEBUG (MainThread) [pyvlx] SEND: <FrameGetNetworkSetupRequest/> 2024-05-25 11:59:02.127 DEBUG (MainThread) [pyvlx] REC: <FrameGetNetworkSetupConfirmation ipaddress="10.111.10.36" netmask="255.255.255.0" gateway="10.111.10.1" dhcp="DHCPParameter.DISABLE"/> 2024-05-25 11:59:02.131 DEBUG (MainThread) [pyvlx] SEND: <FrameHouseStatusMonitorEnableRequest/> 2024-05-25 11:59:03.182 DEBUG (MainThread) [pyvlx] REC: <FrameHouseStatusMonitorEnableConfirmation/> 2024-05-25 11:59:03.188 DEBUG (MainThread) [pyvlx] Heartbeat start 2024-05-25 11:59:03.189 DEBUG (MainThread) [pyvlx] Connecting to KLF 200 was opened 2024-05-25 11:59:03.190 DEBUG (MainThread) [pyvlx] SEND: <FrameHouseStatusMonitorDisableRequest/> 2024-05-25 11:59:03.192 DEBUG (MainThread) [pyvlx] Heartbeat: creating task 2024-05-25 11:59:03.192 DEBUG (MainThread) [pyvlx] Heartbeat: task started 2024-05-25 11:59:03.193 DEBUG (MainThread) [pyvlx] Heartbeat: sleeping 2024-05-25 11:59:04.192 DEBUG (MainThread) [pyvlx] Heartbeat stopped 2024-05-25 11:59:04.198 DEBUG (MainThread) [pyvlx] Connecting to KLF 200 was closed 2024-05-25 11:59:04.203 DEBUG (MainThread) [pyvlx] Heartbeat __init__ 2024-05-25 11:59:04.203 DEBUG (MainThread) [pyvlx] Loadig pyvlx v0.1.76 2024-05-25 11:59:04.203 DEBUG (MainThread) [pyvlx] Connecting to KLF 200 2024-05-25 11:59:04.257 DEBUG (MainThread) [pyvlx] Connecting to KLF 200 was closed 2024-05-25 11:59:04.342 DEBUG (MainThread) [pyvlx] Connecting to KLF 200 was closed 2024-05-25 11:59:08.591 DEBUG (MainThread) [pyvlx] Amount of connections since last HA start: 1 2024-05-25 11:59:08.592 DEBUG (MainThread) [pyvlx] SEND: <FramePasswordEnterRequest password="******"/> 2024-05-25 11:59:08.601 DEBUG (MainThread) [pyvlx] REC: <FramePasswordEnterConfirmation status="PasswordEnterConfirmationStatus.SUCCESSFUL"/> 2024-05-25 11:59:08.610 DEBUG (MainThread) [pyvlx] SEND: <FrameGetVersionRequest/> 2024-05-25 11:59:08.613 DEBUG (MainThread) [pyvlx] REC: <FrameGetVersionConfirmation software_version="0.2.0.0.71.0" hardware_version="6" product="KLF 200"/> 2024-05-25 11:59:08.614 DEBUG (MainThread) [pyvlx] SEND: <FrameGetProtocolVersionRequest/> 2024-05-25 11:59:08.820 DEBUG (MainThread) [pyvlx] REC: <FrameGetProtocolVersionConfirmation version="3.14"/> 2024-05-25 11:59:08.821 DEBUG (MainThread) [pyvlx] Connected to: <DtoVersion softwareversion="0.2.0.0.71.0" hardwareversion="6" productgroup="14" producttype="3"/>, <DtoProtocolVersion majorversion="3" minorversion="14"/> 2024-05-25 11:59:08.821 DEBUG (MainThread) [pyvlx] SEND: <FrameHouseStatusMonitorDisableRequest/> 2024-05-25 11:59:09.037 DEBUG (MainThread) [pyvlx] REC: <FrameHouseStatusMonitorDisableConfirmation/> 2024-05-25 11:59:09.038 DEBUG (MainThread) [pyvlx] SEND: <FrameGetStateRequest/> 2024-05-25 11:59:09.247 DEBUG (MainThread) [pyvlx] REC: <FrameGetStateConfirmation gateway_state="GatewayState.GATEWAY_MODE_WITH_ACTUATORS" gateway_sub_state="GatewaySubState.IDLE"/> 2024-05-25 11:59:09.248 DEBUG (MainThread) [pyvlx] SEND: <FrameSetUTCRequest time="2024-05-25 11:58:17"/> 2024-05-25 11:59:09.458 DEBUG (MainThread) [pyvlx] REC: <FrameSetUTCConfirmation/> 2024-05-25 11:59:09.458 DEBUG (MainThread) [pyvlx] SEND: <FrameGetNetworkSetupRequest/> 2024-05-25 11:59:09.462 DEBUG (MainThread) [pyvlx] REC: <FrameGetNetworkSetupConfirmation ipaddress="10.111.10.36" netmask="255.255.255.0" gateway="10.111.10.1" dhcp="DHCPParameter.DISABLE"/> 2024-05-25 11:59:09.463 DEBUG (MainThread) [pyvlx] SEND: <FrameHouseStatusMonitorEnableRequest/> 2024-05-25 11:59:10.955 DEBUG (MainThread) [pyvlx] REC: <FrameHouseStatusMonitorEnableConfirmation/> 2024-05-25 11:59:10.956 DEBUG (MainThread) [pyvlx] Heartbeat start 2024-05-25 11:59:10.957 DEBUG (MainThread) [pyvlx] Connecting to KLF 200 was opened 2024-05-25 11:59:10.957 DEBUG (MainThread) [pyvlx] SEND: <FrameGetAllNodesInformationRequest/> 2024-05-25 11:59:10.964 DEBUG (MainThread) [pyvlx] Heartbeat: creating task 2024-05-25 11:59:10.964 DEBUG (MainThread) [pyvlx] Heartbeat: task started 2024-05-25 11:59:10.964 DEBUG (MainThread) [pyvlx] Heartbeat: sleeping 2024-05-25 11:59:11.438 DEBUG (MainThread) [pyvlx] REC: <FrameGetAllNodesInformationConfirmation status="AllNodesInformationStatus.OK" number_of_nodes="9"/>