mysql database upgrade from 2.2.1 to current master immediately expires all existing v6 leases
When database is upgraded to newest level (in my case I am upgrading from 2.2.1 version 14 to current master version 21) all existing v6 leases have expiration date set to the time upgrade was executed. So when Kea starts - all leases that were in the database are already expired.
Test scenario:
- start kea-dhcp6 2.2.1 with long validlife time (I'm using 40000 to get different date in database)
- assign 20 leases
- run command
lease6-get-all
and all 20 leases are returned - stop kea
- run kea-admin upgrade script from current master, this will update database to version 21.
- start kea-dhcp6 current master
- run command
lease6-get-all
all 20 leases are returned as valid ones! - run command
"command": "leases-reclaim", "arguments": {"remove": True}}
or wait for lease expiration proces - run command
lease6-get-all
and error is returned because there are no leases in the database.
Database content (please forgive me formatting, and focus mostly on expire column, it has a different date!):
MariaDB [keadb]> select * from schema_version;
+---------+-------+
| version | minor |
+---------+-------+
| 14 | 0 |
+---------+-------+
1 row in set (0.001 sec)
MariaDB [keadb]> select * from lease6;
+------------------+------------+----------------+---------------------+-----------+---------------+------------+------+------------+----------+----------+----------+--------+--------+---------------+-------+------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------+
| address | duid | valid_lifetime | expire | subnet_id | pref_lifetime | lease_type | iaid | prefix_len | fqdn_fwd | fqdn_rev | hostname | hwaddr | hwtype | hwaddr_source | state | user_context |
+------------------+------------+----------------+---------------------+-----------+---------------+------------+------+------------+----------+----------+----------+--------+--------+---------------+-------+------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------+
| 2001:db8:2:0:1:: |
| 40000 | 2024-05-14 09:30:05 | 1 | 3000 | 2 | 7654 | 80 | 0 | 0 | |
| 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } |
| 2001:db8:2:0:2:: |
| 40000 | 2024-05-14 09:30:05 | 1 | 3000 | 2 | 3444 | 80 | 0 | 0 | |
| 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } |
| 2001:db8:2:0:3:: |
| 40000 | 2024-05-14 09:30:05 | 1 | 3000 | 2 | 2365 | 80 | 0 | 0 | |
| 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } |
| 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } |
| 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } |
| 2001:db8:2:0:6:: | | 40000 | 2024-05-14 09:30:06 | 1 | 3000 | 2 | 8315 | 80 | 0 | 0 | | | 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } |
| 2001:db8:2:0:7:: | | 40000 | 2024-05-14 09:30:06 | 1 | 3000 | 2 | 7071 | 80 | 0 | 0 | | | 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } |
| 2001:db8:2:0:8:: | | 40000 | 2024-05-14 09:30:06 | 1 | 3000 | 2 | 8038 | 80 | 0 | 0 | | | 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } |
| 2001:db8:2:0:9:: | | 40000 | 2024-05-14 09:30:06 | 1 | 3000 | 2 | 5694 | 80 | 0 | 0 | | | 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } |
| 2001:db8:2:: |
| 40000 | 2024-05-14 09:30:05 | 1 | 3000 | 2 | 230 | 80 | 0 | 0 | |
| 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } |
| 2001:db8:a::2 |
| 40000 | 2024-05-14 09:30:05 | 1 | 3000 | 0 | 399 | 128 | 0 | 0 | |
| 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } |
| 2001:db8:a::3 |
| 40000 | 2024-05-14 09:30:05 | 1 | 3000 | 0 | 1121 | 128 | 0 | 0 | |
| 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } |
| 2001:db8:a::5 |
| 40000 | 2024-05-14 09:30:05 | 1 | 3000 | 0 | 3125 | 128 | 0 | 0 | |
| 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } |
| 2001:db8:a::6 |
| 40000 | 2024-05-14 09:30:05 | 1 | 3000 | 0 | 3642 | 128 | 0 | 0 | |
| 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } |
| 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } |
| 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } |
| 2001:db8:a::b | | 40000 | 2024-05-14 09:30:06 | 1 | 3000 | 0 | 3100 | 128 | 0 | 0 | | | 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } |
| 2001:db8:a::c | | 40000 | 2024-05-14 09:30:06 | 1 | 3000 | 0 | 6541 | 128 | 0 | 0 | | | 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } |
| 2001:db8:a::e | | 40000 | 2024-05-14 09:30:06 | 1 | 3000 | 0 | 7699 | 128 | 0 | 0 | | | 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } |
| 2001:db8:a::f | | 40000 | 2024-05-14 09:30:06 | 1 | 3000 | 0 | 2069 | 128 | 0 | 0 | | | 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } |
+------------------+------------+----------------+---------------------+-----------+---------------+------------+------+------------+----------+----------+----------+--------+--------+---------------+-------+------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------+
20 rows in set (0.001 sec)
<<<<<<<<<<<<<<<<<<<<<<<<<<<< database upgrade >>>>>>>>>>>>>>>>>>>>>>>>
MariaDB [keadb]> select * from schema_version;
+---------+-------+
| version | minor |
+---------+-------+
| 21 | 0 |
+---------+-------+
1 row in set (0.001 sec)
MariaDB [keadb]> select * from lease6;
+------------------+------------+----------------+---------------------+-----------+---------------+------------+------+------------+----------+----------+----------+--------+--------+---------------+-------+------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------+---------+
| address | duid | valid_lifetime | expire | subnet_id | pref_lifetime | lease_type | iaid | prefix_len | fqdn_fwd | fqdn_rev | hostname | hwaddr | hwtype | hwaddr_source | state | user_context | pool_id |
+------------------+------------+----------------+---------------------+-----------+---------------+------------+------+------------+----------+----------+----------+--------+--------+---------------+-------+------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------+---------+
? |
| 40000 | 2024-05-13 22:23:38 | 1 | 3000 | 2 | 230 | 80 | 0 | 0 | |
| 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } | 0 |
? |
| 40000 | 2024-05-13 22:23:38 | 1 | 3000 | 2 | 7654 | 80 | 0 | 0 | |
| 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } | 0 |
? |
| 40000 | 2024-05-13 22:23:38 | 1 | 3000 | 2 | 3444 | 80 | 0 | 0 | |
| 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } | 0 |
? |
| 40000 | 2024-05-13 22:23:38 | 1 | 3000 | 2 | 2365 | 80 | 0 | 0 | |
| 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } | 0 |
| 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } | 0 |
| 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } | 0 |
? | | 40000 | 2024-05-13 22:23:38 | 1 | 3000 | 2 | 8315 | 80 | 0 | 0 | | | 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } | 0 |
? | | 40000 | 2024-05-13 22:23:38 | 1 | 3000 | 2 | 7071 | 80 | 0 | 0 | | | 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } | 0 |
? | | 40000 | 2024-05-13 22:23:38 | 1 | 3000 | 2 | 8038 | 80 | 0 | 0 | | | 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } | 0 |
? | | 40000 | 2024-05-13 22:23:38 | 1 | 3000 | 2 | 5694 | 80 | 0 | 0 | | | 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } | 0 |
?
|
| 40000 | 2024-05-13 22:23:38 | 1 | 3000 | 0 | 399 | 128 | 0 | 0 | |
| 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } | 0 |
?
|
| 40000 | 2024-05-13 22:23:38 | 1 | 3000 | 0 | 1121 | 128 | 0 | 0 | |
| 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } | 0 |
?
|
| 40000 | 2024-05-13 22:23:38 | 1 | 3000 | 0 | 3125 | 128 | 0 | 0 | |
| 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } | 0 |
?
|
| 40000 | 2024-05-13 22:23:38 | 1 | 3000 | 0 | 3642 | 128 | 0 | 0 | |
| 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } | 0 |
?
| 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } | 0 |
?
| 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } | 0 |
?
| | 40000 | 2024-05-13 22:23:38 | 1 | 3000 | 0 | 3100 | 128 | 0 | 0 | | | 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } | 0 |
?
| | 40000 | 2024-05-13 22:23:38 | 1 | 3000 | 0 | 6541 | 128 | 0 | 0 | | | 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } | 0 |
?
| | 40000 | 2024-05-13 22:23:38 | 1 | 3000 | 0 | 7699 | 128 | 0 | 0 | | | 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } | 0 |
?
| | 40000 | 2024-05-13 22:23:38 | 1 | 3000 | 0 | 2069 | 128 | 0 | 0 | | | 1 | 2 | 0 | { "ISC": { "relays": [ { "hop": 0, "link": "2001:db8:a::1000", "options": "0x0012000231350025000A000000000A00270000010035000A00030001FFFFFFFFFF01", "peer": "fe80::a00:27ff:fe6d:ee67" } ] } } | 0 |
+------------------+------------+----------------+---------------------+-----------+---------------+------------+------+------------+----------+----------+----------+--------+--------+---------------+-------+------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------------+---------+
20 rows in set (0.002 sec)
Logs from Kea 2.5.9:
2024-05-13 22:23:53.319 INFO [kea-dhcp6.dhcp6/2480354.140144846852608] DHCP6_STARTED Kea DHCPv6 server version 2.5.9-git started
2024-05-13 22:23:57.044 DEBUG [kea-dhcp6.commands/2480354.140144846852608] COMMAND_SOCKET_CONNECTION_OPENED Opened socket 22 for incoming command connection
2024-05-13 22:23:57.045 DEBUG [kea-dhcp6.commands/2480354.140144846852608] COMMAND_SOCKET_READ Received 89 bytes over command socket 22
2024-05-13 22:23:57.045 INFO [kea-dhcp6.commands/2480354.140144846852608] COMMAND_RECEIVED Received command 'lease6-get-all'
2024-05-13 22:23:57.045 DEBUG [kea-dhcp6.callouts/2480354.140144846852608] HOOKS_CALLOUTS_BEGIN begin all callouts for hook $lease6_get_all
2024-05-13 22:23:57.045 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_MYSQL_GET6 obtaining all IPv6 leases
2024-05-13 22:23:57.046 DEBUG [kea-dhcp6.callouts/2480354.140144846852608] HOOKS_CALLOUT_CALLED hooks library with index 1 has called a callout on hook $lease6_get_all that has address 0x7f7601cddc00 (callout duration: 1.715 ms)
2024-05-13 22:23:57.047 DEBUG [kea-dhcp6.callouts/2480354.140144846852608] HOOKS_CALLOUTS_COMPLETE completed callouts for hook $lease6_get_all (total callouts duration: 1.715 ms)
2024-05-13 22:23:57.047 DEBUG [kea-dhcp6.commands/2480354.140144846852608] COMMAND_SOCKET_WRITE Sent response of 10183 bytes (0 bytes left to send) over command socket 22 <<<<<<<<<<<<<<<<<<<<< 10183 bytes, those are 20 leases
2024-05-13 22:23:57.047 DEBUG [kea-dhcp6.commands/2480354.140144846852608] COMMAND_SOCKET_CONNECTION_CLOSED Closed socket 22 for existing command connection
2024-05-13 22:24:00.199 DEBUG [kea-dhcp6.commands/2480354.140144846852608] COMMAND_SOCKET_CONNECTION_OPENED Opened socket 22 for incoming command connection
2024-05-13 22:24:00.199 DEBUG [kea-dhcp6.commands/2480354.140144846852608] COMMAND_SOCKET_READ Received 122 bytes over command socket 22
2024-05-13 22:24:00.199 INFO [kea-dhcp6.commands/2480354.140144846852608] COMMAND_RECEIVED Received command 'leases-reclaim'
2024-05-13 22:24:00.199 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_V6_LEASES_RECLAMATION_START starting reclamation of expired leases (limit = 0 leases or 0 milliseconds)
2024-05-13 22:24:00.199 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_MYSQL_GET_EXPIRED6 obtaining maximum 0 of expired IPv6 leases
2024-05-13 22:24:00.200 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_V6_LEASE_RECLAIM duid=[00:03:00:01:01:03:0d:04:0b:01], [hwtype=1 01:03:0d:04:0b:01]: reclaiming expired lease for prefix 2001:db8:2::/80
2024-05-13 22:24:00.200 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_MYSQL_DELETE_ADDR deleting lease for address 2001:db8:2::
2024-05-13 22:24:00.203 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_LEASE_RECLAIMED successfully reclaimed lease 2001:db8:2::
2024-05-13 22:24:00.203 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_V6_LEASE_RECLAIM duid=[00:03:00:01:01:03:0d:04:0b:01], [hwtype=1 01:03:0d:04:0b:01]: reclaiming expired lease for prefix 2001:db8:2:0:1::/80
2024-05-13 22:24:00.203 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_MYSQL_DELETE_ADDR deleting lease for address 2001:db8:2:0:1::
2024-05-13 22:24:00.204 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_LEASE_RECLAIMED successfully reclaimed lease 2001:db8:2:0:1::
2024-05-13 22:24:00.204 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_V6_LEASE_RECLAIM duid=[00:03:00:01:01:04:0e:05:0c:02], [hwtype=1 01:04:0e:05:0c:02]: reclaiming expired lease for prefix 2001:db8:2:0:2::/80
2024-05-13 22:24:00.204 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_MYSQL_DELETE_ADDR deleting lease for address 2001:db8:2:0:2::
2024-05-13 22:24:00.208 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_LEASE_RECLAIMED successfully reclaimed lease 2001:db8:2:0:2::
2024-05-13 22:24:00.208 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_V6_LEASE_RECLAIM duid=[00:03:00:01:01:04:0e:05:0c:02], [hwtype=1 01:04:0e:05:0c:02]: reclaiming expired lease for prefix 2001:db8:2:0:3::/80
2024-05-13 22:24:00.208 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_MYSQL_DELETE_ADDR deleting lease for address 2001:db8:2:0:3::
2024-05-13 22:24:00.210 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_LEASE_RECLAIMED successfully reclaimed lease 2001:db8:2:0:3::
2024-05-13 22:24:00.210 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_V6_LEASE_RECLAIM duid=[00:03:00:01:01:05:0f:06:0d:03], [hwtype=1 01:05:0f:06:0d:03]: reclaiming expired lease for prefix 2001:db8:2:0:4::/80
2024-05-13 22:24:00.210 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_MYSQL_DELETE_ADDR deleting lease for address 2001:db8:2:0:4::
2024-05-13 22:24:00.212 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_LEASE_RECLAIMED successfully reclaimed lease 2001:db8:2:0:4::
2024-05-13 22:24:00.212 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_V6_LEASE_RECLAIM duid=[00:03:00:01:01:05:0f:06:0d:03], [hwtype=1 01:05:0f:06:0d:03]: reclaiming expired lease for prefix 2001:db8:2:0:5::/80
2024-05-13 22:24:00.212 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_MYSQL_DELETE_ADDR deleting lease for address 2001:db8:2:0:5::
2024-05-13 22:24:00.214 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_LEASE_RECLAIMED successfully reclaimed lease 2001:db8:2:0:5::
2024-05-13 22:24:00.214 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_V6_LEASE_RECLAIM duid=[00:03:00:01:01:06:10:07:0e:04], [hwtype=1 01:06:10:07:0e:04]: reclaiming expired lease for prefix 2001:db8:2:0:6::/80
2024-05-13 22:24:00.214 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_MYSQL_DELETE_ADDR deleting lease for address 2001:db8:2:0:6::
2024-05-13 22:24:00.215 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_LEASE_RECLAIMED successfully reclaimed lease 2001:db8:2:0:6::
2024-05-13 22:24:00.215 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_V6_LEASE_RECLAIM duid=[00:03:00:01:01:06:10:07:0e:04], [hwtype=1 01:06:10:07:0e:04]: reclaiming expired lease for prefix 2001:db8:2:0:7::/80
2024-05-13 22:24:00.215 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_MYSQL_DELETE_ADDR deleting lease for address 2001:db8:2:0:7::
2024-05-13 22:24:00.217 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_LEASE_RECLAIMED successfully reclaimed lease 2001:db8:2:0:7::
2024-05-13 22:24:00.217 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_V6_LEASE_RECLAIM duid=[00:03:00:01:01:07:11:08:0f:05], [hwtype=1 01:07:11:08:0f:05]: reclaiming expired lease for prefix 2001:db8:2:0:8::/80
2024-05-13 22:24:00.217 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_MYSQL_DELETE_ADDR deleting lease for address 2001:db8:2:0:8::
2024-05-13 22:24:00.218 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_LEASE_RECLAIMED successfully reclaimed lease 2001:db8:2:0:8::
2024-05-13 22:24:00.218 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_V6_LEASE_RECLAIM duid=[00:03:00:01:01:07:11:08:0f:05], [hwtype=1 01:07:11:08:0f:05]: reclaiming expired lease for prefix 2001:db8:2:0:9::/80
2024-05-13 22:24:00.218 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_MYSQL_DELETE_ADDR deleting lease for address 2001:db8:2:0:9::
2024-05-13 22:24:00.219 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_LEASE_RECLAIMED successfully reclaimed lease 2001:db8:2:0:9::
2024-05-13 22:24:00.219 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_V6_LEASE_RECLAIM duid=[00:03:00:01:01:03:0d:04:0b:01], [hwtype=1 01:03:0d:04:0b:01]: reclaiming expired lease for prefix 2001:db8:a::2/128
2024-05-13 22:24:00.219 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_QUEUE_NCR_SKIP duid=[00:03:00:01:01:03:0d:04:0b:01], [hwtype=1 01:03:0d:04:0b:01]: skip queuing name change request for lease: 2001:db8:a::2
2024-05-13 22:24:00.219 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_MYSQL_DELETE_ADDR deleting lease for address 2001:db8:a::2
2024-05-13 22:24:00.220 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_LEASE_RECLAIMED successfully reclaimed lease 2001:db8:a::2
2024-05-13 22:24:00.220 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_V6_LEASE_RECLAIM duid=[00:03:00:01:01:03:0d:04:0b:01], [hwtype=1 01:03:0d:04:0b:01]: reclaiming expired lease for prefix 2001:db8:a::3/128
2024-05-13 22:24:00.220 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_QUEUE_NCR_SKIP duid=[00:03:00:01:01:03:0d:04:0b:01], [hwtype=1 01:03:0d:04:0b:01]: skip queuing name change request for lease: 2001:db8:a::3
2024-05-13 22:24:00.220 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_MYSQL_DELETE_ADDR deleting lease for address 2001:db8:a::3
2024-05-13 22:24:00.222 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_LEASE_RECLAIMED successfully reclaimed lease 2001:db8:a::3
2024-05-13 22:24:00.222 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_V6_LEASE_RECLAIM duid=[00:03:00:01:01:04:0e:05:0c:02], [hwtype=1 01:04:0e:05:0c:02]: reclaiming expired lease for prefix 2001:db8:a::5/128
2024-05-13 22:24:00.222 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_QUEUE_NCR_SKIP duid=[00:03:00:01:01:04:0e:05:0c:02], [hwtype=1 01:04:0e:05:0c:02]: skip queuing name change request for lease: 2001:db8:a::5
2024-05-13 22:24:00.222 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_MYSQL_DELETE_ADDR deleting lease for address 2001:db8:a::5
2024-05-13 22:24:00.223 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_LEASE_RECLAIMED successfully reclaimed lease 2001:db8:a::5
2024-05-13 22:24:00.223 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_V6_LEASE_RECLAIM duid=[00:03:00:01:01:04:0e:05:0c:02], [hwtype=1 01:04:0e:05:0c:02]: reclaiming expired lease for prefix 2001:db8:a::6/128
2024-05-13 22:24:00.223 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_QUEUE_NCR_SKIP duid=[00:03:00:01:01:04:0e:05:0c:02], [hwtype=1 01:04:0e:05:0c:02]: skip queuing name change request for lease: 2001:db8:a::6
2024-05-13 22:24:00.223 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_MYSQL_DELETE_ADDR deleting lease for address 2001:db8:a::6
2024-05-13 22:24:00.225 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_LEASE_RECLAIMED successfully reclaimed lease 2001:db8:a::6
2024-05-13 22:24:00.225 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_V6_LEASE_RECLAIM duid=[00:03:00:01:01:05:0f:06:0d:03], [hwtype=1 01:05:0f:06:0d:03]: reclaiming expired lease for prefix 2001:db8:a::8/128
2024-05-13 22:24:00.225 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_QUEUE_NCR_SKIP duid=[00:03:00:01:01:05:0f:06:0d:03], [hwtype=1 01:05:0f:06:0d:03]: skip queuing name change request for lease: 2001:db8:a::8
2024-05-13 22:24:00.225 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_MYSQL_DELETE_ADDR deleting lease for address 2001:db8:a::8
2024-05-13 22:24:00.226 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_LEASE_RECLAIMED successfully reclaimed lease 2001:db8:a::8
2024-05-13 22:24:00.226 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_V6_LEASE_RECLAIM duid=[00:03:00:01:01:05:0f:06:0d:03], [hwtype=1 01:05:0f:06:0d:03]: reclaiming expired lease for prefix 2001:db8:a::9/128
2024-05-13 22:24:00.226 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_QUEUE_NCR_SKIP duid=[00:03:00:01:01:05:0f:06:0d:03], [hwtype=1 01:05:0f:06:0d:03]: skip queuing name change request for lease: 2001:db8:a::9
2024-05-13 22:24:00.226 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_MYSQL_DELETE_ADDR deleting lease for address 2001:db8:a::9
2024-05-13 22:24:00.227 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_LEASE_RECLAIMED successfully reclaimed lease 2001:db8:a::9
2024-05-13 22:24:00.227 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_V6_LEASE_RECLAIM duid=[00:03:00:01:01:06:10:07:0e:04], [hwtype=1 01:06:10:07:0e:04]: reclaiming expired lease for prefix 2001:db8:a::b/128
2024-05-13 22:24:00.227 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_QUEUE_NCR_SKIP duid=[00:03:00:01:01:06:10:07:0e:04], [hwtype=1 01:06:10:07:0e:04]: skip queuing name change request for lease: 2001:db8:a::b
2024-05-13 22:24:00.227 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_MYSQL_DELETE_ADDR deleting lease for address 2001:db8:a::b
2024-05-13 22:24:00.229 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_LEASE_RECLAIMED successfully reclaimed lease 2001:db8:a::b
2024-05-13 22:24:00.229 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_V6_LEASE_RECLAIM duid=[00:03:00:01:01:06:10:07:0e:04], [hwtype=1 01:06:10:07:0e:04]: reclaiming expired lease for prefix 2001:db8:a::c/128
2024-05-13 22:24:00.229 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_QUEUE_NCR_SKIP duid=[00:03:00:01:01:06:10:07:0e:04], [hwtype=1 01:06:10:07:0e:04]: skip queuing name change request for lease: 2001:db8:a::c
2024-05-13 22:24:00.229 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_MYSQL_DELETE_ADDR deleting lease for address 2001:db8:a::c
2024-05-13 22:24:00.230 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_LEASE_RECLAIMED successfully reclaimed lease 2001:db8:a::c
2024-05-13 22:24:00.230 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_V6_LEASE_RECLAIM duid=[00:03:00:01:01:07:11:08:0f:05], [hwtype=1 01:07:11:08:0f:05]: reclaiming expired lease for prefix 2001:db8:a::e/128
2024-05-13 22:24:00.230 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_QUEUE_NCR_SKIP duid=[00:03:00:01:01:07:11:08:0f:05], [hwtype=1 01:07:11:08:0f:05]: skip queuing name change request for lease: 2001:db8:a::e
2024-05-13 22:24:00.230 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_MYSQL_DELETE_ADDR deleting lease for address 2001:db8:a::e
2024-05-13 22:24:00.231 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_LEASE_RECLAIMED successfully reclaimed lease 2001:db8:a::e
2024-05-13 22:24:00.231 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_V6_LEASE_RECLAIM duid=[00:03:00:01:01:07:11:08:0f:05], [hwtype=1 01:07:11:08:0f:05]: reclaiming expired lease for prefix 2001:db8:a::f/128
2024-05-13 22:24:00.231 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_QUEUE_NCR_SKIP duid=[00:03:00:01:01:07:11:08:0f:05], [hwtype=1 01:07:11:08:0f:05]: skip queuing name change request for lease: 2001:db8:a::f
2024-05-13 22:24:00.231 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_MYSQL_DELETE_ADDR deleting lease for address 2001:db8:a::f
2024-05-13 22:24:00.233 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_LEASE_RECLAIMED successfully reclaimed lease 2001:db8:a::f
2024-05-13 22:24:00.233 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_V6_LEASES_RECLAMATION_COMPLETE reclaimed 20 leases in 33.904 ms
2024-05-13 22:24:00.233 DEBUG [kea-dhcp6.alloc-engine/2480354.140144846852608] ALLOC_ENGINE_V6_NO_MORE_EXPIRED_LEASES all expired leases have been reclaimed
2024-05-13 22:24:00.233 DEBUG [kea-dhcp6.commands/2480354.140144846852608] COMMAND_SOCKET_WRITE Sent response of 69 bytes (0 bytes left to send) over command socket 22
2024-05-13 22:24:00.233 DEBUG [kea-dhcp6.commands/2480354.140144846852608] COMMAND_SOCKET_CONNECTION_CLOSED Closed socket 22 for existing command connection
2024-05-13 22:24:00.238 DEBUG [kea-dhcp6.commands/2480354.140144846852608] COMMAND_SOCKET_CONNECTION_OPENED Opened socket 22 for incoming command connection
2024-05-13 22:24:00.238 DEBUG [kea-dhcp6.commands/2480354.140144846852608] COMMAND_SOCKET_READ Received 89 bytes over command socket 22
2024-05-13 22:24:00.238 INFO [kea-dhcp6.commands/2480354.140144846852608] COMMAND_RECEIVED Received command 'lease6-get-all'
2024-05-13 22:24:00.238 DEBUG [kea-dhcp6.callouts/2480354.140144846852608] HOOKS_CALLOUTS_BEGIN begin all callouts for hook $lease6_get_all
2024-05-13 22:24:00.238 DEBUG [kea-dhcp6.dhcpsrv/2480354.140144846852608] DHCPSRV_MYSQL_GET6 obtaining all IPv6 leases
2024-05-13 22:24:00.239 DEBUG [kea-dhcp6.callouts/2480354.140144846852608] HOOKS_CALLOUT_CALLED hooks library with index 1 has called a callout on hook $lease6_get_all that has address 0x7f7601cddc00 (callout duration: 0.601 ms)
2024-05-13 22:24:00.239 DEBUG [kea-dhcp6.callouts/2480354.140144846852608] HOOKS_CALLOUTS_COMPLETE completed callouts for hook $lease6_get_all (total callouts duration: 0.601 ms)
2024-05-13 22:24:00.239 DEBUG [kea-dhcp6.commands/2480354.140144846852608] COMMAND_SOCKET_WRITE Sent response of 82 bytes (0 bytes left to send) over command socket 22 <<<<<<<<<<<<<<<<<<<<<<< only 82 bytes returned
2024-05-13 22:24:00.239 DEBUG [kea-dhcp6.commands/2480354.140144846852608] COMMAND_SOCKET_CONNECTION_CLOSED Closed socket 22 for existing command connection
- It's only mysql problem
- It's NOT happening when updating from 2.4.1 to current master