<!doctype html>
<html>
 <head> 
  <meta charset="UTF-8"> 
 </head>
 <body>
  <div>
   Here is probably a hint for the problem as seen in the control agent logs:
  </div>
  <div class="default-style">
   HTTP_CLIENT_REQUEST_TIMEOUT_OCCURRED
  </div>
  <div class="default-style">
   and
  </div>
  <div class="default-style">
   HTTP_CONNECTION_STOP
  </div>
  <div class="default-style">
   <br>
  </div>
  <div class="default-style">
   Next question is : how/where is the timeout of "10" defined ?
  </div>
  <div class="default-style">
   <br>
  </div>
  <div class="default-style">
   2022-01-28 15:10:50.419 DEBUG [kea-ctrl-agent.http/17978.140214125492416] HTTP_CONNECTION_HANDSHAKE_START start TLS handshake with kea2 with timeout 10
   <br>2022-01-28 15:10:50.419 DEBUG [kea-ctrl-agent.http/17978.140214125492416] HTTP_CLIENT_REQUEST_TIMEOUT_OCCURRED HTTP request timeout occurred when communicating with (unknown address)
   <br>2022-01-28 15:10:50.419 DEBUG [kea-ctrl-agent.http/17978.140214125492416] HTTP_CONNECTION_STOP stopping HTTP connection from (unknown address)
   <br>2022-01-28 15:10:50.419 DEBUG [kea-ctrl-agent.http/17978.140214125492416] HTTP_CONNECTION_HANDSHAKE_START start TLS handshake with kea2 with timeout 10
   <br>2022-01-28 15:10:50.420 DEBUG [kea-ctrl-agent.http/17978.140214125492416] HTTP_CONNECTION_STOP stopping HTTP connection from (unknown address)
   <br>2022-01-28 15:10:50.421 INFO [kea-ctrl-agent.http/17978.140214125492416] HTTP_CONNECTION_HANDSHAKE_FAILED TLS handshake with (unknown address) failed with short read
   <br>2022-01-28 15:10:50.421 DEBUG [kea-ctrl-agent.http/17978.140214125492416] HTTP_CONNECTION_STOP stopping HTTP connection from (unknown address)
   <br>2022-01-28 15:10:50.494 DEBUG [kea-ctrl-agent.http/17978.140214125492416] HTTPS_REQUEST_RECEIVE_START start receiving request from kea2
   <br>2022-01-28 15:10:50.494 DEBUG [kea-ctrl-agent.http/17978.140214125492416] HTTP_DATA_RECEIVED received 146 bytes from kea2
   <br>2022-01-28 15:10:50.495 DEBUG [kea-ctrl-agent.http/17978.140214125492416] HTTP_CLIENT_REQUEST_RECEIVED received HTTP request from kea2
   <br>2022-01-28 15:10:50.495 DEBUG [kea-ctrl-agent.http/17978.140214125492416] HTTP_CLIENT_REQUEST_RECEIVED_DETAILS detailed information about well-formed request received from kea2:
   <br>
  </div>
  <blockquote type="cite">
   <div>
    On 28/01/2022 13:11 Veronique Lefebure <veronique.lefebure@cern.ch> wrote:
   </div>
   <div>
    <br>
   </div>
   <div>
    <br>
   </div>
   <div class="default-style">
    Hi,
   </div>
   <div class="default-style">
    <br>
   </div>
   <div class="default-style">
    I would like to get a DEBUG logging each time a server receives a heartbeat from its partner, in a HA setup.
   </div>
   <div class="default-style">
    I have enabled DEBUG level 99 for kea-dhcp4.ha-hooks but it does not provide that info,
   </div>
   <div class="default-style">
    <br>
   </div>
   <div class="default-style">
    I am trying to understand why our standby server shows the following every 2 min:
   </div>
   <div class="default-style">
    <br>
   </div>
   <div class="default-style">
    2022-01-28 13:02:30.869 WARN [kea-dhcp4.ha-hooks/22827.140145289767104] HA_HEARTBEAT_COMMUNICATIONS_FAILED failed to send heartbeat to kea1 (http://xx.253:9099/): Connection timed out 
    <br>2022-01-28 13:02:30.869 WARN [kea-dhcp4.ha-hooks/22827.140145289767104] HA_COMMUNICATION_INTERRUPTED communication with kea1 is interrupted 
    <br>
   </div>
   <div class="default-style">
    <br>
   </div>
   <div class="default-style">
    <br>
   </div>
   <div class="default-style">
    And the primary server then oscillates like this (we have only 1 client for now for test purpose):
   </div>
   <div class="default-style">
    <br>
   </div>
   <div class="default-style">
    <div class="default-style">
     2022-01-28 13:06:31.887 INFO [kea-dhcp4.ha-hooks/12849.140566551173312] HA_STATE_TRANSITION server transitions from HOT-STANDBY to WAITING state, partner state is PARTNER-DOWN 
     <br>2022-01-28 13:06:31.887 INFO [kea-dhcp4.ha-hooks/12849.140566551173312] HA_LEASE_UPDATES_DISABLED lease updates will not be sent to the partner while in WAITING state 
     <br>2022-01-28 13:06:31.887 INFO [kea-dhcp4.ha-hooks/12849.140566551173312] HA_LOCAL_DHCP_DISABLE local DHCP service is disabled while the kea1 is in the WAITING state 
     <br>2022-01-28 13:06:31.887 INFO [kea-dhcp4.ha-hooks/12849.140566551173312] HA_STATE_TRANSITION server transitions from WAITING to SYNCING state, partner state is PARTNER-DOWN 
     <br>2022-01-28 13:06:31.887 INFO [kea-dhcp4.ha-hooks/12849.140566551173312] HA_LEASE_UPDATES_DISABLED lease updates will not be sent to the partner while in SYNCING state 
     <br>2022-01-28 13:06:31.888 INFO [kea-dhcp4.ha-hooks/12849.140566551173312] HA_SYNC_START starting lease database synchronization with kea2 
     <br>2022-01-28 13:06:31.972 INFO [kea-dhcp4.ha-hooks/12849.140566551173312] HA_LEASES_SYNC_LEASE_PAGE_RECEIVED received 1 leases from kea2 
     <br>2022-01-28 13:06:31.972 DEBUG [kea-dhcp4.ha-hooks/12849.140566551173312] HA_LEASE_SYNC_STALE_LEASE4_SKIP skipping stale lease xx.xx.xx.xx in subnet 2156789248 
     <br>2022-01-28 13:06:31.975 INFO [kea-dhcp4.ha-hooks/12849.140566551173312] HA_SYNC_SUCCESSFUL lease database synchronization with kea2 completed successfully in 87.082 ms 
     <br>2022-01-28 13:06:31.976 INFO [kea-dhcp4.ha-hooks/12849.140566551173312] HA_STATE_TRANSITION server transitions from SYNCING to READY state, partner state is PARTNER-DOWN 
     <br>2022-01-28 13:06:31.976 INFO [kea-dhcp4.ha-hooks/12849.140566551173312] HA_LEASE_UPDATES_DISABLED lease updates will not be sent to the partner while in READY state
    </div>
    <div class="default-style">
     <br>
    </div>
    <div class="default-style">
     <br>
    </div>
    <div class="default-style">
     <br>2022-01-28 13:06:52.532 INFO [kea-dhcp4.ha-hooks/12849.140566551173312] HA_STATE_TRANSITION server transitions from READY to HOT-STANDBY state, partner state is HOT-STANDBY 
     <br>2022-01-28 13:06:52.532 INFO [kea-dhcp4.ha-hooks/12849.140566551173312] HA_LEASE_UPDATES_ENABLED lease updates will be sent to the partner while in HOT-STANDBY state 
     <br>2022-01-28 13:06:52.532 INFO [kea-dhcp4.ha-hooks/12849.140566551173312] HA_LOCAL_DHCP_ENABLE local DHCP service is enabled while the kea1 is in the HOT-STANDBY state 
     <br>
    </div>
    <div class="default-style">
     <br>
    </div>
    <div class="default-style">
     Thanks.
    </div>
   </div>_______________________________________________
   <br>ISC funds the development of this software with paid support subscriptions. Contact us at https://www.isc.org/contact/ for more information.
   <br>
   <br>To unsubscribe visit https://lists.isc.org/mailman/listinfo/kea-users.
   <br>
   <br>Kea-users mailing list
   <br>Kea-users@lists.isc.org
   <br>https://lists.isc.org/mailman/listinfo/kea-users
   <br>
  </blockquote>
 </body>
</html>