Hi Kea developers,
I'm an user of your production.
I setup and install Kea 1.6 + MariabDB cluster.
I inserted a subnet and a pool to database for testing configuration backend.
When I started server and used "config-get" to get configuration, it didn't
load the subnet and the pool I inserted before.
I tried to lease an IP but it responsed "failed to select subnet for the
client" in kea log.
What could be the problem there and How to fixed them ?
Part of server's configuration, kea dhcp4 log and lease log is attached
Thank you,
Duy Vo
"Dhcp4": {
"server-tag": "my-dhcp4",
"interfaces-config": {
"interfaces": [ "enp0s8" ],
"dhcp-socket-type": "raw"
},
"control-socket": {
"socket-type": "unix",
"socket-name": "/tmp/kea-dhcp4-ctrl.sock"
},
"lease-database": {
"type": "mysql",
"name": "name",
"user": "user",
"password": "pass",
"host": "localhost",
"port": 3306
},
"config-control": {
"config-databases": [
{
"type": "mysql",
"name": "name",
"user": "user",
"password": "pass",
"host": "localhost",
"port": 3306
}
],
"config-fetch-wait-time": 20
},
"expired-leases-processing": {
"reclaim-timer-wait-time": 10,
"flush-reclaimed-timer-wait-time": 25,
"hold-reclaimed-time": 3600,
"max-reclaim-leases": 100,
"max-reclaim-time": 250,
"unwarned-reclaim-cycles": 5
},
"hooks-libraries": [
{
"library": "/opt/kea/lib/kea/hooks/libdhcp_mysql_cb.so"
}
],
"loggers": [
{
"name": "kea-dhcp4",
"output_options": [
{
"output": "/opt/kea/var/log/kea-dhcp4.log",
"flush": true,
"maxsize": 1048576
}
],
"severity": "DEBUG",
"debuglevel": 99
}
]
}
}2019-12-05 14:46:03.434 DEBUG [kea-dhcp4.dhcp4/3946] DHCP4_CONFIG_RECEIVED
received configuration { "authoritative": true, "config-control": {
"config-databases": [ { "host": "localhost", "name": "kea_database",
"password": "pass", "port": 3306, "type": "mysql", "user": "kea" } ],
"config-fetch-wait-time": 20 }, "control-socket": { "socket-name":
"/tmp/kea-dhcp4-ctrl.sock", "socket-type": "unix" },
"expired-leases-processing": { "flush-reclaimed-timer-wait-time": 25,
"hold-reclaimed-time": 3600, "max-reclaim-leases": 100, "max-reclaim-time":
250, "reclaim-timer-wait-time": 10, "unwarned-reclaim-cycles": 5 },
"hooks-libraries": [ { "library": "/opt/kea/lib/kea/hooks/libdhcp_mysql_cb.so"
} ], "interfaces-config": { "dhcp-socket-type": "raw", "interfaces": [ "enp0s8"
] }, "lease-database": { "host": "localhost", "name": "kea_database",
"password": "pass", "port": 3306, "type": "mysql", "user": "kea" }, "loggers":
[ { "debuglevel": 99, "name": "kea-dhcp4", "output_options": [ { "flush": true,
"maxsize": 1048576, "output": "/opt/kea/var/log/kea-dhcp4.log" } ], "severity":
"DEBUG" } ], "server-tag": "my-dhcp4" }
2019-12-05 14:46:03.446 DEBUG [kea-dhcp4.dhcp4/3946] DHCP4_CONFIG_START DHCPv4
server is processing the following configuration: { "authoritative": true,
"config-control": { "config-databases": [ { "host": "localhost", "name":
"kea_database", "password": "pass", "port": 3306, "type": "mysql", "user":
"kea" } ], "config-fetch-wait-time": 20 }, "control-socket": { "socket-name":
"/tmp/kea-dhcp4-ctrl.sock", "socket-type": "unix" },
"expired-leases-processing": { "flush-reclaimed-timer-wait-time": 25,
"hold-reclaimed-time": 3600, "max-reclaim-leases": 100, "max-reclaim-time":
250, "reclaim-timer-wait-time": 10, "unwarned-reclaim-cycles": 5 },
"hooks-libraries": [ { "library": "/opt/kea/lib/kea/hooks/libdhcp_mysql_cb.so"
} ], "interfaces-config": { "dhcp-socket-type": "raw", "interfaces": [ "enp0s8"
] }, "lease-database": { "host": "localhost", "name": "kea_database",
"password": "pass", "port": 3306, "type": "mysql", "user": "kea" }, "loggers":
[ { "debuglevel": 99, "name": "kea-dhcp4", "output_options": [ { "flush": true,
"maxsize": 1048576, "output": "/opt/kea/var/log/kea-dhcp4.log" } ], "severity":
"DEBUG" } ], "server-tag": "my-dhcp4" }
2019-12-05 14:46:03.447 DEBUG [kea-dhcp4.dhcpsrv/3946]
DHCPSRV_TIMERMGR_UNREGISTER_ALL_TIMERS unregistering all timers
2019-12-05 14:46:03.447 INFO [kea-dhcp4.hosts/3946] HOSTS_BACKENDS_REGISTERED
the following host backend types are available: mysql
2019-12-05 14:46:03.472 DEBUG [kea-dhcp4.hooks/3946] HOOKS_LIBRARY_VERSION
hooks library /opt/kea/lib/kea/hooks/libdhcp_mysql_cb.so reports its version as
10
2019-12-05 14:46:03.473 INFO [kea-dhcp4.dhcpsrv/3946]
DHCPSRV_CFGMGR_SOCKET_TYPE_SELECT using socket type raw
2019-12-05 14:46:03.473 INFO [kea-dhcp4.dhcpsrv/3946]
DHCPSRV_CFGMGR_USE_ADDRESS listening on address 192.168.56.102, on interface
enp0s8
2019-12-05 14:46:03.479 INFO [kea-dhcp4.commands/3946] COMMAND_ACCEPTOR_START
Starting to accept connections via unix domain socket bound to
/tmp/kea-dhcp4-ctrl.sock
2019-12-05 14:46:03.480 DEBUG [kea-dhcp4.dhcpsrv/3946]
DHCPSRV_CFGMGR_CFG_DHCP_DDNS Setting DHCP-DDNS configuration to: DHCP-DDNS
updates disabled
2019-12-05 14:46:03.481 DEBUG [kea-dhcp4.hooks/3946] HOOKS_LIBRARY_LOADING
loading hooks library /opt/kea/lib/kea/hooks/libdhcp_mysql_cb.so
2019-12-05 14:46:03.499 DEBUG [kea-dhcp4.hooks/3946] HOOKS_LIBRARY_VERSION
hooks library /opt/kea/lib/kea/hooks/libdhcp_mysql_cb.so reports its version as
10
2019-12-05 14:46:03.500 INFO [kea-dhcp4.mysql-cb-hooks/3946] MYSQL_CB_INIT_OK
loading MYSQL CB hooks library successful
2019-12-05 14:46:03.500 DEBUG [kea-dhcp4.mysql-cb-hooks/3946]
MYSQL_CB_REGISTER_BACKEND_TYPE4 register backend
2019-12-05 14:46:03.501 DEBUG [kea-dhcp4.mysql-cb-hooks/3946]
MYSQL_CB_REGISTER_BACKEND_TYPE6 register backend
2019-12-05 14:46:03.501 DEBUG [kea-dhcp4.hooks/3946] HOOKS_LOAD_SUCCESS 'load'
function in hook library /opt/kea/lib/kea/hooks/libdhcp_mysql_cb.so returned
success
2019-12-05 14:46:03.501 INFO [kea-dhcp4.hooks/3946] HOOKS_LIBRARY_LOADED hooks
library /opt/kea/lib/kea/hooks/libdhcp_mysql_cb.so successfully loaded
2019-12-05 14:46:03.505 INFO [kea-dhcp4.dctl/3946] DCTL_OPEN_CONFIG_DB Opening
configuration database: host=localhost name=kea_database password=*****
port=3306 type=mysql user=kea
2019-12-05 14:46:03.673 INFO [kea-dhcp4.dctl/3946] DCTL_CONFIG_FETCH Fetching
configuration data from config backends.
2019-12-05 14:46:03.673 DEBUG [kea-dhcp4.mysql-cb-hooks/3946]
MYSQL_CB_GET_RECENT_AUDIT_ENTRIES4 retrieving audit entries from: 1970-01-01
00:00:00.000000
2019-12-05 14:46:03.674 DEBUG [kea-dhcp4.mysql-cb-hooks/3946]
MYSQL_CB_GET_RECENT_AUDIT_ENTRIES4_RESULT retrieving: 0 elements
2019-12-05 14:46:03.674 DEBUG [kea-dhcp4.mysql-cb-hooks/3946]
MYSQL_CB_GET_MODIFIED_GLOBAL_PARAMETERS4 retrieving modified global parameters
from: 1970-01-01 00:00:00.000000
2019-12-05 14:46:03.675 DEBUG [kea-dhcp4.mysql-cb-hooks/3946]
MYSQL_CB_GET_MODIFIED_GLOBAL_PARAMETERS4_RESULT retrieving: 0 elements
2019-12-05 14:46:03.675 DEBUG [kea-dhcp4.mysql-cb-hooks/3946]
MYSQL_CB_GET_MODIFIED_OPTION_DEFS4 retrieving modified option definitions from:
1970-01-01 00:00:00.000000
2019-12-05 14:46:03.676 DEBUG [kea-dhcp4.mysql-cb-hooks/3946]
MYSQL_CB_GET_MODIFIED_OPTION_DEFS4_RESULT retrieving: 0 elements
2019-12-05 14:46:03.676 DEBUG [kea-dhcp4.mysql-cb-hooks/3946]
MYSQL_CB_GET_MODIFIED_OPTIONS4 retrieving modified options from: 1970-01-01
00:00:00.000000
2019-12-05 14:46:03.678 DEBUG [kea-dhcp4.mysql-cb-hooks/3946]
MYSQL_CB_GET_MODIFIED_OPTIONS4_RESULT retrieving: 0 elements
2019-12-05 14:46:03.678 DEBUG [kea-dhcp4.mysql-cb-hooks/3946]
MYSQL_CB_GET_MODIFIED_SHARED_NETWORKS4 retrieving modified shared networks
from: 1970-01-01 00:00:00.000000
2019-12-05 14:46:03.680 DEBUG [kea-dhcp4.mysql-cb-hooks/3946]
MYSQL_CB_GET_MODIFIED_SHARED_NETWORKS4_RESULT retrieving: 0 elements
2019-12-05 14:46:03.680 DEBUG [kea-dhcp4.mysql-cb-hooks/3946]
MYSQL_CB_GET_MODIFIED_SUBNETS4 retrieving modified subnets from: 1970-01-01
00:00:00.000000
2019-12-05 14:46:03.683 DEBUG [kea-dhcp4.mysql-cb-hooks/3946]
MYSQL_CB_GET_MODIFIED_SUBNETS4_RESULT retrieving: 0 elements
2019-12-05 14:46:03.683 INFO [kea-dhcp4.dhcpsrv/3946]
DHCPSRV_CFGMGR_CONFIG4_MERGED Configuration backend data has been merged.
2019-12-05 14:46:03.683 INFO [kea-dhcp4.dhcp4/3946] DHCP4_CONFIG_COMPLETE
DHCPv4 server has completed configuration: no IPv4 subnets!; DDNS: disabled
2019-12-05 14:46:03.683 INFO [kea-dhcp4.dhcpsrv/3946] DHCPSRV_MYSQL_DB opening
MySQL lease database: host=localhost name=kea_database password=***** port=3306
type=mysql universe=4 user=kea
2019-12-05 14:46:03.687 DEBUG [kea-dhcp4.dhcpsrv/3946]
DHCPSRV_MYSQL_GET_VERSION obtaining schema version information
2019-12-05 14:46:03.725 DEBUG [kea-dhcp4.dhcpsrv/3946]
DHCPSRV_TIMERMGR_REGISTER_TIMER registering timer: reclaim-expired-leases,
using interval: 10000 ms
2019-12-05 14:46:03.725 DEBUG [kea-dhcp4.dhcpsrv/3946]
DHCPSRV_TIMERMGR_START_TIMER starting timer: reclaim-expired-leases
2019-12-05 14:46:03.726 DEBUG [kea-dhcp4.dhcpsrv/3946]
DHCPSRV_TIMERMGR_REGISTER_TIMER registering timer: flush-reclaimed-leases,
using interval: 25000 ms
2019-12-05 14:46:03.726 DEBUG [kea-dhcp4.dhcpsrv/3946]
DHCPSRV_TIMERMGR_START_TIMER starting timer: flush-reclaimed-leases
2019-12-05 14:46:03.726 DEBUG [kea-dhcp4.dhcpsrv/3946]
DHCPSRV_TIMERMGR_REGISTER_TIMER registering timer: Dhcp4CBFetchTimer, using
interval: 20000 ms
2019-12-05 14:46:03.726 DEBUG [kea-dhcp4.dhcpsrv/3946]
DHCPSRV_TIMERMGR_START_TIMER starting timer: Dhcp4CBFetchTimer
2019-12-05 14:46:03.727 INFO [kea-dhcp4.dhcp4/3946] DHCP4_STARTED Kea DHCPv4
server version 1.6.0 started
2019-12-05 14:46:07.062 DEBUG [kea-dhcp4.packets/3946] DHCP4_BUFFER_WAIT_SIGNAL
signal received while waiting for next packet, next waiting signal is 15
2019-12-05 14:46:07.063 DEBUG [kea-dhcp4.dhcp4/3946] DHCP4_COMMAND_RECEIVED
received command shutdown, arguments: (none)
2019-12-05 14:46:07.063 DEBUG [kea-dhcp4.dhcp4/3946] DHCP4_SHUTDOWN_REQUEST
shutdown of server requested
2019-12-05 14:46:07.063 INFO [kea-dhcp4.dhcp4/3946] DHCP4_SHUTDOWN server
shutdown
2019-12-05 14:46:07.063 DEBUG [kea-dhcp4.dhcpsrv/3946]
DHCPSRV_TIMERMGR_UNREGISTER_ALL_TIMERS unregistering all timers
2019-12-05 14:46:07.063 DEBUG [kea-dhcp4.commands/3946] COMMAND_DEREGISTERED
Command build-report deregistered
2019-12-05 14:46:07.064 DEBUG [kea-dhcp4.commands/3946] COMMAND_DEREGISTERED
Command config-get deregistered
2019-12-05 14:46:07.064 DEBUG [kea-dhcp4.commands/3946] COMMAND_DEREGISTERED
Command config-reload deregistered
2019-12-05 14:46:07.069 DEBUG [kea-dhcp4.commands/3946] COMMAND_DEREGISTERED
Command config-test deregistered
2019-12-05 14:46:07.069 DEBUG [kea-dhcp4.commands/3946] COMMAND_DEREGISTERED
Command config-write deregistered
2019-12-05 14:46:07.069 DEBUG [kea-dhcp4.commands/3946] COMMAND_DEREGISTERED
Command leases-reclaim deregistered
2019-12-05 14:46:07.069 DEBUG [kea-dhcp4.commands/3946] COMMAND_DEREGISTERED
Command libreload deregistered
2019-12-05 14:46:07.069 DEBUG [kea-dhcp4.commands/3946] COMMAND_DEREGISTERED
Command config-set deregistered
2019-12-05 14:46:07.069 DEBUG [kea-dhcp4.commands/3946] COMMAND_DEREGISTERED
Command dhcp-disable deregistered
2019-12-05 14:46:07.069 DEBUG [kea-dhcp4.commands/3946] COMMAND_DEREGISTERED
Command dhcp-enable deregistered
2019-12-05 14:46:07.069 DEBUG [kea-dhcp4.commands/3946] COMMAND_DEREGISTERED
Command server-tag-get deregistered
2019-12-05 14:46:07.070 DEBUG [kea-dhcp4.commands/3946] COMMAND_DEREGISTERED
Command shutdown deregistered
2019-12-05 14:46:07.070 DEBUG [kea-dhcp4.commands/3946] COMMAND_DEREGISTERED
Command statistic-get deregistered
2019-12-05 14:46:07.070 DEBUG [kea-dhcp4.commands/3946] COMMAND_DEREGISTERED
Command statistic-get-all deregistered
2019-12-05 14:46:07.070 DEBUG [kea-dhcp4.commands/3946] COMMAND_DEREGISTERED
Command statistic-remove deregistered
2019-12-05 14:46:07.070 DEBUG [kea-dhcp4.commands/3946] COMMAND_DEREGISTERED
Command statistic-remove-all deregistered
2019-12-05 14:46:07.070 DEBUG [kea-dhcp4.commands/3946] COMMAND_DEREGISTERED
Command statistic-reset deregistered
2019-12-05 14:46:07.070 DEBUG [kea-dhcp4.commands/3946] COMMAND_DEREGISTERED
Command statistic-reset-all deregistered
2019-12-05 14:46:07.070 DEBUG [kea-dhcp4.commands/3946] COMMAND_DEREGISTERED
Command statistic-sample-age-set deregistered
2019-12-05 14:46:07.071 DEBUG [kea-dhcp4.commands/3946] COMMAND_DEREGISTERED
Command statistic-sample-age-set-all deregistered
2019-12-05 14:46:07.071 DEBUG [kea-dhcp4.commands/3946] COMMAND_DEREGISTERED
Command statistic-sample-count-set deregistered
2019-12-05 14:46:07.071 DEBUG [kea-dhcp4.commands/3946] COMMAND_DEREGISTERED
Command statistic-sample-count-set-all deregistered
2019-12-05 14:46:07.071 DEBUG [kea-dhcp4.commands/3946] COMMAND_DEREGISTERED
Command version-get deregistered
2019-12-05 14:46:07.071 DEBUG [kea-dhcp4.dhcpsrv/3946] DHCPSRV_CLOSE_DB closing
currently open mysql database
2019-12-05 14:46:07.079 DEBUG [kea-dhcp4.hooks/3946] HOOKS_LIBRARY_UNLOADING
unloading library /opt/kea/lib/kea/hooks/libdhcp_mysql_cb.so
2019-12-05 14:46:07.079 INFO [kea-dhcp4.mysql-cb-hooks/3946]
MYSQL_CB_DEINIT_OK unloading MYSQL CB hooks library successful
2019-12-05 14:46:07.079 DEBUG [kea-dhcp4.mysql-cb-hooks/3946]
MYSQL_CB_UNREGISTER_BACKEND_TYPE4 unregister backend
2019-12-05 14:46:07.079 DEBUG [kea-dhcp4.mysql-cb-hooks/3946]
MYSQL_CB_GET_TYPE4 get type
2019-12-05 14:46:07.119 DEBUG [kea-dhcp4.mysql-cb-hooks/3946]
MYSQL_CB_UNREGISTER_BACKEND_TYPE6 unregister backend
2019-12-05 14:46:07.119 DEBUG [kea-dhcp4.hooks/3946] HOOKS_UNLOAD_SUCCESS
'unload' function in hook library /opt/kea/lib/kea/hooks/libdhcp_mysql_cb.so
returned success
2019-12-05 14:46:07.119 INFO [kea-dhcp4.hooks/3946] HOOKS_LIBRARY_UNLOADED
hooks library /opt/kea/lib/kea/hooks/libdhcp_mysql_cb.so successfully unloaded2019-12-05 14:51:51.164 DEBUG [kea-dhcp4.packets/4041] DHCP4_BUFFER_RECEIVED
received buffer from 0.0.0.0:68 to 192.168.56.102:67 over interface enp0s8
2019-12-05 14:51:51.164 DEBUG [kea-dhcp4.options/4041] DHCP4_BUFFER_UNPACK
parsing buffer received from 0.0.0.0 to 192.168.56.102 over interface enp0s8
2019-12-05 14:51:51.166 DEBUG [kea-dhcp4.packets/4041] DHCP4_PACKET_RECEIVED
[hwtype=1 08:00:27:46:a4:b7], cid=[no info], tid=0x33dcc655: DHCPDISCOVER (type
1) received from 0.0.0.0 to 192.168.56.102 on interface enp0s8
2019-12-05 14:51:51.166 DEBUG [kea-dhcp4.packets/4041] DHCP4_QUERY_DATA
[hwtype=1 08:00:27:46:a4:b7], cid=[no info], tid=0x33dcc655, packet details:
local_address=192.168.56.102:67, remote_address=0.0.0.0:68,
msg_type=DHCPDISCOVER (1), transid=0x33dcc655,
options:
type=012, len=020: "vokhacduy-VirtualBox" (string)
type=050, len=004: 192.168.56.102 (ipv4-address)
type=053, len=001: 1 (uint8)
type=055, len=013: 1(uint8) 28(uint8) 2(uint8) 3(uint8) 15(uint8) 6(uint8)
119(uint8) 12(uint8) 44(uint8) 47(uint8) 26(uint8) 121(uint8) 42(uint8)
2019-12-05 14:51:51.166 DEBUG [kea-dhcp4.packets/4041]
DHCP4_SUBNET_SELECTION_FAILED [hwtype=1 08:00:27:46:a4:b7], cid=[no info],
tid=0x33dcc655: failed to select subnet for the client
2019-12-05 14:51:51.166 DEBUG [kea-dhcp4.dhcp4/4041] DHCP4_CLASS_ASSIGNED
[hwtype=1 08:00:27:46:a4:b7], cid=[no info], tid=0x33dcc655: client packet has
been assigned to the following class(es): UNKNOWN
2019-12-05 14:51:51.166 DEBUG [kea-dhcp4.dhcp4/4041] DHCP4_CLASS_ASSIGNED
[hwtype=1 08:00:27:46:a4:b7], cid=[no info], tid=0x33dcc655: client packet has
been assigned to the following class(es): ALL, UNKNOWN
2019-12-05 14:51:51.166 DEBUG [kea-dhcp4.ddns/4041]
DHCP4_CLIENT_HOSTNAME_PROCESS [hwtype=1 08:00:27:46:a4:b7], cid=[no info],
tid=0x33dcc655: processing client's Hostname option
2019-12-05 14:51:51.166 DEBUG [kea-dhcp4.ddns/4041] DHCP4_CLIENT_HOSTNAME_DATA
[hwtype=1 08:00:27:46:a4:b7], cid=[no info], tid=0x33dcc655: client sent
Hostname option: vokhacduy-VirtualBox
2019-12-05 14:51:51.167 DEBUG [kea-dhcp4.ddns/4041] DHCP4_CLIENT_HOSTNAME_DATA
[hwtype=1 08:00:27:46:a4:b7], cid=[no info], tid=0x33dcc655: client sent
Hostname option: vokhacduy-VirtualBox
2019-12-05 14:51:51.167 DEBUG [kea-dhcp4.ddns/4041]
DHCP4_RESPONSE_HOSTNAME_DATA [hwtype=1 08:00:27:46:a4:b7], cid=[no info],
tid=0x33dcc655: including Hostname option in the server's response:
vokhacduy-virtualbox
2019-12-05 14:51:51.167 ERROR [kea-dhcp4.bad-packets/4041]
DHCP4_PACKET_NAK_0001 [hwtype=1 08:00:27:46:a4:b7], cid=[no info],
tid=0x33dcc655: failed to select a subnet for incoming packet, src 0.0.0.0,
type DHCPDISCOVER
2019-12-05 14:51:53.170 DEBUG [kea-dhcp4.dhcpsrv/4041]
DHCPSRV_TIMERMGR_RUN_TIMER_OPERATION running operation for timer:
flush-reclaimed-leases
2019-12-05 14:51:53.170 DEBUG [kea-dhcp4.alloc-engine/4041]
ALLOC_ENGINE_V4_RECLAIMED_LEASES_DELETE begin deletion of reclaimed leases
expired more than 3600 seconds ago
2019-12-05 14:51:53.170 DEBUG [kea-dhcp4.dhcpsrv/4041]
DHCPSRV_MYSQL_DELETE_EXPIRED_RECLAIMED4 deleting reclaimed IPv4 leases that
expired more than 3600 seconds ago
2019-12-05 14:51:53.172 DEBUG [kea-dhcp4.dhcpsrv/4041]
DHCPSRV_MYSQL_DELETED_EXPIRED_RECLAIMED deleted 0 reclaimed leases from the
database
2019-12-05 14:51:53.172 DEBUG [kea-dhcp4.alloc-engine/4041]
ALLOC_ENGINE_V4_RECLAIMED_LEASES_DELETE_COMPLETE successfully deleted 0
expired-reclaimed leases
2019-12-05 14:51:53.172 DEBUG [kea-dhcp4.dhcpsrv/4041]
DHCPSRV_TIMERMGR_START_TIMER starting timer: flush-reclaimed-leases
_______________________________________________
Kea-users mailing list
Kea-users@lists.isc.org
https://lists.isc.org/mailman/listinfo/kea-users
