Like many others, I am evaluating Kea as a replacement for ISC DHCP. While I can get Kea to behave mostly as I like, I am seeing some odd behaviours when I use shared networks. Here's one:
A single phone is assigned an IP address (via dhcrelay). I deliberately set the renew-timer to a low value (100 seconds). When the phone sends a DHCPREQUEST for the same address (after 100 seconds), the server sends a DHCPNAK. This causes the phone to reboot and restart the DHCP cycle. The second time around the phone succeeds in getting an IP address but a different one. I was running Kea with debugging turned on so I have attached the output (as well as the configuration file and a packet trace). Here's the lease file showing the same MAC getting different IP addresses: address,hwaddr,client_id,valid_lifetime,expire,subnet_id,fqdn_fwd,fqdn_rev,hostname,state 10.148.1.68,08:00:0f:6c:c5:a9,01:08:00:0f:6c:c5:a9,400,1508526744,2,0,0,,0 10.148.1.69,08:00:0f:6c:c5:a9,01:08:00:0f:6c:c5:a9,400,1508526896,2,0,0,,0 More on the environment: * Kea 1.3.0 beta * SLES12 server with more than enough CPU, RAM etc Any thoughts? Rob
memfile2.json
Description: memfile2.json
Script started on Fri Oct 20 15:04:48 2017
1;31mroot@vlab275n0# 0m/usr/local/sbin/kea-dhcp4 -d -c ~/memfile2.json
2017-10-20 15:04:53.548 DEBUG [kea-dhcp4.dhcp4/9984] DHCP4_START_INFO pid:
9984, port: 67, verbose: yes
2017-10-20 15:04:53.548 INFO [kea-dhcp4.dhcp4/9984] DHCP4_STARTING Kea DHCPv4
server version 1.3.0-beta starting
2017-10-20 15:04:53.548 DEBUG [kea-dhcp4.dhcp4/9984] DHCP4_OPEN_SOCKET opening
sockets on port 67
2017-10-20 15:04:53.548 DEBUG [kea-dhcp4.commands/9984] COMMAND_REGISTERED
Command list-commands registered
2017-10-20 15:04:53.548 DEBUG [kea-dhcp4.commands/9984] COMMAND_REGISTERED
Command build-report registered
2017-10-20 15:04:53.548 DEBUG [kea-dhcp4.commands/9984] COMMAND_REGISTERED
Command config-get registered
2017-10-20 15:04:53.548 DEBUG [kea-dhcp4.commands/9984] COMMAND_REGISTERED
Command config-reload registered
2017-10-20 15:04:53.548 DEBUG [kea-dhcp4.commands/9984] COMMAND_REGISTERED
Command config-set registered
2017-10-20 15:04:53.548 DEBUG [kea-dhcp4.commands/9984] COMMAND_REGISTERED
Command config-test registered
2017-10-20 15:04:53.548 DEBUG [kea-dhcp4.commands/9984] COMMAND_REGISTERED
Command config-write registered
2017-10-20 15:04:53.548 DEBUG [kea-dhcp4.commands/9984] COMMAND_REGISTERED
Command libreload registered
2017-10-20 15:04:53.548 DEBUG [kea-dhcp4.commands/9984] COMMAND_REGISTERED
Command leases-reclaim registered
2017-10-20 15:04:53.548 DEBUG [kea-dhcp4.commands/9984] COMMAND_REGISTERED
Command shutdown registered
2017-10-20 15:04:53.548 DEBUG [kea-dhcp4.commands/9984] COMMAND_REGISTERED
Command version-get registered
2017-10-20 15:04:53.548 DEBUG [kea-dhcp4.commands/9984] COMMAND_REGISTERED
Command statistic-get registered
2017-10-20 15:04:53.548 DEBUG [kea-dhcp4.commands/9984] COMMAND_REGISTERED
Command statistic-reset registered
2017-10-20 15:04:53.548 DEBUG [kea-dhcp4.commands/9984] COMMAND_REGISTERED
Command statistic-remove registered
2017-10-20 15:04:53.548 DEBUG [kea-dhcp4.commands/9984] COMMAND_REGISTERED
Command statistic-get-all registered
2017-10-20 15:04:53.548 DEBUG [kea-dhcp4.commands/9984] COMMAND_REGISTERED
Command statistic-reset-all registered
2017-10-20 15:04:53.548 DEBUG [kea-dhcp4.commands/9984] COMMAND_REGISTERED
Command statistic-remove-all registered
2017-10-20 15:04:53.549 DEBUG [kea-dhcp4.dhcp4/9984] DHCP4_COMMAND_RECEIVED
received command config-set, arguments: { "Dhcp4": { "client-classes": [ {
"name": "mitel", "option-data": [ { "data":
"id:ipphone.mitel.com;sw_tftp=10.151.75.34;call_srv=10.151.75.32", "name":
"mitel-options", "space": "mitel" }, { "name": "vendor-encapsulated-options" },
{ "code": 128, "csv-format": true, "data": "10.151.75.34", "name":
"MitelTfpServer", "space": "dhcp4" }, { "code": 129, "csv-format": true,
"data": "10.151.75.32", "name": "MitelTas" }, { "code": 130, "csv-format":
true, "data": "MITEL IP PHONE" } ], "option-def": [ { "code": 43,
"encapsulate": "mitel", "name": "vendor-encapsulated-options", "type": "empty"
} ], "test": "substring(option[60].hex,0,17) == 'ipphone.mitel.com'" } ],
"echo-client-id": false, "expired-leases-processing": {
"flush-reclaimed-timer-wait-time": 300, "hold-reclaimed-time": 1800,
"max-reclaim-leases": 500, "max-reclaim-time": 100, "reclaim-timer-wait-time":
300, "unwarned-reclaim-cycles": 10 }, "interfaces-config": { "interfaces": [
"eth1/10.151.75.34" ] }, "lease-database": { "lfc-interval": 1800, "name":
"/usr/local/var/kea/dhcp4.leases", "persist": true, "type": "memfile" },
"option-data": [ { "code": 66, "csv-format": true, "data": "10.151.75.34",
"name": "tftp-server-name", "space": "dhcp4" } ], "option-def": [ { "array":
false, "code": 128, "encapsulate": "", "name": "MitelTfpServer",
"record-types": "", "space": "dhcp4", "type": "ipv4-address" }, { "array":
false, "code": 129, "encapsulate": "", "name": "MitelTas", "record-types": "",
"space": "dhcp4", "type": "ipv4-address" }, { "array": false, "code": 130,
"encapsulate": "", "name": "MitelQualifier", "record-types": "", "space":
"dhcp4", "type": "string" }, { "code": 1, "name": "mitel-options", "space":
"mitel", "type": "string" } ], "renew-timer": 100, "shared-networks": [ {
"name": "That-awful-mess", "subnet4": [ { "option-data": [ { "data":
"10.148.1.1", "name": "routers" } ], "pools": [ { "pool": "10.148.1.4 -
10.148.1.6" } ], "subnet": "10.148.1.0/28" }, { "option-data": [ { "data":
"10.148.1.65", "name": "routers" } ], "pools": [ { "pool": "10.148.1.68 -
10.148.1.71" } ], "subnet": "10.148.1.64/28" }, { "option-data": [ { "data":
"10.148.1.81", "name": "routers" } ], "pools": [ { "pool": "10.148.1.84 -
10.148.1.86" } ], "subnet": "10.148.1.80/28" } ] } ], "valid-lifetime": 400 },
"Logging": { "loggers": [ { "name": "kea-dhcp4", "output_options": [ {
"output": "stdout" } ], "severity": "INFO" } ] } }
2017-10-20 15:04:53.549 DEBUG [kea-dhcp4.dhcp4/9984] DHCP4_CONFIG_RECEIVED
received configuration { "client-classes": [ { "name": "mitel", "option-data":
[ { "data": "id:ipphone.mitel.com;sw_tftp=10.151.75.34;call_srv=10.151.75.32",
"name": "mitel-options", "space": "mitel" }, { "name":
"vendor-encapsulated-options" }, { "code": 128, "csv-format": true, "data":
"10.151.75.34", "name": "MitelTfpServer", "space": "dhcp4" }, { "code": 129,
"csv-format": true, "data": "10.151.75.32", "name": "MitelTas" }, { "code":
130, "csv-format": true, "data": "MITEL IP PHONE" } ], "option-def": [ {
"code": 43, "encapsulate": "mitel", "name": "vendor-encapsulated-options",
"type": "empty" } ], "test": "substring(option[60].hex,0,17) ==
'ipphone.mitel.com'" } ], "echo-client-id": false, "expired-leases-processing":
{ "flush-reclaimed-timer-wait-time": 300, "hold-reclaimed-time": 1800,
"max-reclaim-leases": 500, "max-reclaim-time": 100, "reclaim-timer-wait-time":
300, "unwarned-reclaim-cycles": 10 }, "interfaces-config": { "interfaces": [
"eth1/10.151.75.34" ] }, "lease-database": { "lfc-interval": 1800, "name":
"/usr/local/var/kea/dhcp4.leases", "persist": true, "type": "memfile" },
"option-data": [ { "code": 66, "csv-format": true, "data": "10.151.75.34",
"name": "tftp-server-name", "space": "dhcp4" } ], "option-def": [ { "array":
false, "code": 128, "encapsulate": "", "name": "MitelTfpServer",
"record-types": "", "space": "dhcp4", "type": "ipv4-address" }, { "array":
false, "code": 129, "encapsulate": "", "name": "MitelTas", "record-types": "",
"space": "dhcp4", "type": "ipv4-address" }, { "array": false, "code": 130,
"encapsulate": "", "name": "MitelQualifier", "record-types": "", "space":
"dhcp4", "type": "string" }, { "code": 1, "name": "mitel-options", "space":
"mitel", "type": "string" } ], "renew-timer": 100, "shared-networks": [ {
"name": "That-awful-mess", "subnet4": [ { "option-data": [ { "data":
"10.148.1.1", "name": "routers" } ], "pools": [ { "pool": "10.148.1.4 -
10.148.1.6" } ], "subnet": "10.148.1.0/28" }, { "option-data": [ { "data":
"10.148.1.65", "name": "routers" } ], "pools": [ { "pool": "10.148.1.68 -
10.148.1.71" } ], "subnet": "10.148.1.64/28" }, { "option-data": [ { "data":
"10.148.1.81", "name": "routers" } ], "pools": [ { "pool": "10.148.1.84 -
10.148.1.86" } ], "subnet": "10.148.1.80/28" } ] } ], "valid-lifetime": 400 }
2017-10-20 15:04:53.549 DEBUG [kea-dhcp4.dhcp4/9984] DHCP4_CONFIG_START DHCPv4
server is processing the following configuration: { "client-classes": [ {
"name": "mitel", "option-data": [ { "data":
"id:ipphone.mitel.com;sw_tftp=10.151.75.34;call_srv=10.151.75.32", "name":
"mitel-options", "space": "mitel" }, { "name": "vendor-encapsulated-options" },
{ "code": 128, "csv-format": true, "data": "10.151.75.34", "name":
"MitelTfpServer", "space": "dhcp4" }, { "code": 129, "csv-format": true,
"data": "10.151.75.32", "name": "MitelTas" }, { "code": 130, "csv-format":
true, "data": "MITEL IP PHONE" } ], "option-def": [ { "code": 43,
"encapsulate": "mitel", "name": "vendor-encapsulated-options", "type": "empty"
} ], "test": "substring(option[60].hex,0,17) == 'ipphone.mitel.com'" } ],
"echo-client-id": false, "expired-leases-processing": {
"flush-reclaimed-timer-wait-time": 300, "hold-reclaimed-time": 1800,
"max-reclaim-leases": 500, "max-reclaim-time": 100, "reclaim-timer-wait-time":
300, "unwarned-reclaim-cycles": 10 }, "interfaces-config": { "interfaces": [
"eth1/10.151.75.34" ] }, "lease-database": { "lfc-interval": 1800, "name":
"/usr/local/var/kea/dhcp4.leases", "persist": true, "type": "memfile" },
"option-data": [ { "code": 66, "csv-format": true, "data": "10.151.75.34",
"name": "tftp-server-name", "space": "dhcp4" } ], "option-def": [ { "array":
false, "code": 128, "encapsulate": "", "name": "MitelTfpServer",
"record-types": "", "space": "dhcp4", "type": "ipv4-address" }, { "array":
false, "code": 129, "encapsulate": "", "name": "MitelTas", "record-types": "",
"space": "dhcp4", "type": "ipv4-address" }, { "array": false, "code": 130,
"encapsulate": "", "name": "MitelQualifier", "record-types": "", "space":
"dhcp4", "type": "string" }, { "code": 1, "name": "mitel-options", "space":
"mitel", "type": "string" } ], "renew-timer": 100, "shared-networks": [ {
"name": "That-awful-mess", "subnet4": [ { "option-data": [ { "data":
"10.148.1.1", "name": "routers" } ], "pools": [ { "pool": "10.148.1.4 -
10.148.1.6" } ], "subnet": "10.148.1.0/28" }, { "option-data": [ { "data":
"10.148.1.65", "name": "routers" } ], "pools": [ { "pool": "10.148.1.68 -
10.148.1.71" } ], "subnet": "10.148.1.64/28" }, { "option-data": [ { "data":
"10.148.1.81", "name": "routers" } ], "pools": [ { "pool": "10.148.1.84 -
10.148.1.86" } ], "subnet": "10.148.1.80/28" } ] } ], "valid-lifetime": 400 }
2017-10-20 15:04:53.549 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_TIMERMGR_UNREGISTER_ALL_TIMERS unregistering all timers
2017-10-20 15:04:53.562 INFO [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_CFGMGR_USE_ADDRESS listening on address 10.151.75.34, on interface eth1
2017-10-20 15:04:53.562 INFO [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_CFGMGR_SOCKET_TYPE_DEFAULT "dhcp-socket-type" not specified , using
default socket type raw
2017-10-20 15:04:53.563 INFO [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_CFGMGR_NEW_SUBNET4 a new subnet has been added to configuration:
10.148.1.0/28 with params: t1=100, t2=1800, valid-lifetime=400
2017-10-20 15:04:53.563 INFO [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_CFGMGR_NEW_SUBNET4 a new subnet has been added to configuration:
10.148.1.64/28 with params: t1=100, t2=1800, valid-lifetime=400
2017-10-20 15:04:53.563 INFO [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_CFGMGR_NEW_SUBNET4 a new subnet has been added to configuration:
10.148.1.80/28 with params: t1=100, t2=1800, valid-lifetime=400
2017-10-20 15:04:53.563 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_CFGMGR_ADD_SUBNET4 adding subnet 10.148.1.0/28
2017-10-20 15:04:53.563 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_CFGMGR_ADD_SUBNET4 adding subnet 10.148.1.64/28
2017-10-20 15:04:53.563 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_CFGMGR_ADD_SUBNET4 adding subnet 10.148.1.80/28
2017-10-20 15:04:53.563 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_CFGMGR_CFG_DHCP_DDNS Setting DHCP-DDNS configuration to: DHCP-DDNS
updates disabled
2017-10-20 15:04:53.564 INFO [kea-dhcp4.dhcp4/9984] DHCP4_CONFIG_COMPLETE
DHCPv4 server has completed configuration: added IPv4 subnets: 3; DDNS: disabled
2017-10-20 15:04:53.564 INFO [kea-dhcp4.dhcpsrv/9984] DHCPSRV_MEMFILE_DB
opening memory file lease database: lfc-interval=1800
name=/usr/local/var/kea/dhcp4.leases persist=true type=memfile universe=4
2017-10-20 15:04:53.564 INFO [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_LEASE_FILE_LOAD loading leases from file
/usr/local/var/kea/dhcp4.leases
2017-10-20 15:04:53.564 INFO [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_LFC_SETUP setting up the Lease File Cleanup interval to 1800 sec
2017-10-20 15:04:53.564 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_TIMERMGR_REGISTER_TIMER registering timer: memfile-lfc, using interval:
1800000 ms
2017-10-20 15:04:53.564 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_TIMERMGR_START_TIMER starting timer: memfile-lfc
2017-10-20 15:04:53.579 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_TIMERMGR_REGISTER_TIMER registering timer: reclaim-expired-leases,
using interval: 300000 ms
2017-10-20 15:04:53.579 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_TIMERMGR_START_TIMER starting timer: reclaim-expired-leases
2017-10-20 15:04:53.579 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_TIMERMGR_REGISTER_TIMER registering timer: flush-reclaimed-leases,
using interval: 300000 ms
2017-10-20 15:04:53.579 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_TIMERMGR_START_TIMER starting timer: flush-reclaimed-leases
2017-10-20 15:04:53.580 INFO [kea-dhcp4.dhcp4/9984] DHCP4_STARTED Kea DHCPv4
server version 1.3.0-beta started
2017-10-20 15:05:44.468 DEBUG [kea-dhcp4.packets/9984] DHCP4_BUFFER_RECEIVED
received buffer from 10.148.1.65:67 to 10.151.75.34:67 over interface eth1
2017-10-20 15:05:44.468 DEBUG [kea-dhcp4.options/9984] DHCP4_BUFFER_UNPACK
parsing buffer received from 10.148.1.65 to 10.151.75.34 over interface eth1
2017-10-20 15:05:44.468 DEBUG [kea-dhcp4.eval/9984] EVAL_DEBUG_OPTION Pushing
option 60 with value 0x697070686F6E652E6D6974656C2E636F6D00
2017-10-20 15:05:44.468 DEBUG [kea-dhcp4.eval/9984] EVAL_DEBUG_STRING Pushing
text string '0'
2017-10-20 15:05:44.468 DEBUG [kea-dhcp4.eval/9984] EVAL_DEBUG_STRING Pushing
text string '17'
2017-10-20 15:05:44.468 DEBUG [kea-dhcp4.eval/9984] EVAL_DEBUG_SUBSTRING
Popping length 17, start 0, string 0x697070686F6E652E6D6974656C2E636F6D00
pushing result 0x697070686F6E652E6D6974656C2E636F6D
2017-10-20 15:05:44.468 DEBUG [kea-dhcp4.eval/9984] EVAL_DEBUG_STRING Pushing
text string 'ipphone.mitel.com'
2017-10-20 15:05:44.468 DEBUG [kea-dhcp4.eval/9984] EVAL_DEBUG_EQUAL Popping
0x697070686F6E652E6D6974656C2E636F6D and 0x697070686F6E652E6D6974656C2E636F6D
pushing result 'true'
2017-10-20 15:05:44.468 INFO [kea-dhcp4.options/9984] EVAL_RESULT Expression
mitel evaluated to 1
2017-10-20 15:05:44.468 DEBUG [kea-dhcp4.packets/9984] DHCP4_PACKET_RECEIVED
[hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9], tid=0x8c5fb101:
DHCPDISCOVER (type 1) received from 10.148.1.65 to 10.151.75.34 on interface
eth1
2017-10-20 15:05:44.468 DEBUG [kea-dhcp4.packets/9984] DHCP4_QUERY_DATA
[hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9], tid=0x8c5fb101,
packet details: local_address=10.151.75.34:67, remote_address=10.148.1.65:67,
msg_type=DHCPDISCOVER (1), transid=0x8c5fb101,
options:
type=053, len=001: 1 (uint8)
type=055, len=026: 1(uint8) 3(uint8) 6(uint8) 12(uint8) 42(uint8) 44(uint8)
51(uint8) 54(uint8) 58(uint8) 59(uint8) 66(uint8) 120(uint8) 129(uint8)
128(uint8) 130(uint8) 131(uint8) 132(uint8) 133(uint8) 134(uint8) 135(uint8)
224(uint8) 138(uint8) 125(uint8) 43(uint8) 178(uint8) 179(uint8)
type=057, len=002: 1500 (uint16)
type=060, len=018: "ipphone.mitel.com" (string)
type=061, len=007: 01:08:00:0f:6c:c5:a9
type=124, len=5, enterprise id=0x403, data-len0=0, vendor-class-data0=''
2017-10-20 15:05:44.468 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_CFGMGR_SUBNET4_ADDR selected subnet 10.148.1.64/28 for packet received
by matching address 10.148.1.65
2017-10-20 15:05:44.468 DEBUG [kea-dhcp4.packets/9984] DHCP4_SUBNET_SELECTED
[hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9], tid=0x8c5fb101: the
subnet with ID 2 was selected for client assignments
2017-10-20 15:05:44.468 DEBUG [kea-dhcp4.packets/9984] DHCP4_SUBNET_DATA
[hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9], tid=0x8c5fb101: the
selected subnet details: 10.148.1.64/28
2017-10-20 15:05:44.468 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for
subnet id 2, identified by hwaddr=08000F6CC5A9
2017-10-20 15:05:44.468 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier:
hwaddr=08000F6CC5A9
2017-10-20 15:05:44.468 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier hwaddr=08000F6CC5A9, found
0 host(s)
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 2
and identifier hwaddr=08000F6CC5A9
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for
subnet id 2, identified by client-id=0108000F6CC5A9
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier:
client-id=0108000F6CC5A9
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier client-id=0108000F6CC5A9,
found 0 host(s)
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 2
and identifier client-id=0108000F6CC5A9
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for
subnet id 3, identified by hwaddr=08000F6CC5A9
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier:
hwaddr=08000F6CC5A9
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier hwaddr=08000F6CC5A9, found
0 host(s)
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 3
and identifier hwaddr=08000F6CC5A9
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for
subnet id 3, identified by client-id=0108000F6CC5A9
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier:
client-id=0108000F6CC5A9
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier client-id=0108000F6CC5A9,
found 0 host(s)
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 3
and identifier client-id=0108000F6CC5A9
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for
subnet id 1, identified by hwaddr=08000F6CC5A9
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier:
hwaddr=08000F6CC5A9
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier hwaddr=08000F6CC5A9, found
0 host(s)
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 1
and identifier hwaddr=08000F6CC5A9
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for
subnet id 1, identified by client-id=0108000F6CC5A9
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier:
client-id=0108000F6CC5A9
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier client-id=0108000F6CC5A9,
found 0 host(s)
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 1
and identifier client-id=0108000F6CC5A9
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.dhcp4/9984] DHCP4_CLASS_ASSIGNED
[hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9], tid=0x8c5fb101:
client packet has been assigned to the following class(es):
VENDOR_CLASS_ipphone.mitel.com, mitel
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.ddns/9984]
DHCP4_CLIENT_HOSTNAME_PROCESS [hwtype=1 08:00:0f:6c:c5:a9],
cid=[01:08:00:0f:6c:c5:a9], tid=0x8c5fb101: processing client's Hostname option
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_SUBID_CLIENTID obtaining IPv4 lease for subnet ID 2 and
client ID 01:08:00:0f:6c:c5:a9
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_HWADDR obtaining IPv4 leases for hardware address hwtype=1
08:00:0f:6c:c5:a9
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_SUBID_CLIENTID obtaining IPv4 lease for subnet ID 3 and
client ID 01:08:00:0f:6c:c5:a9
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_HWADDR obtaining IPv4 leases for hardware address hwtype=1
08:00:0f:6c:c5:a9
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_SUBID_CLIENTID obtaining IPv4 lease for subnet ID 1 and
client ID 01:08:00:0f:6c:c5:a9
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_HWADDR obtaining IPv4 leases for hardware address hwtype=1
08:00:0f:6c:c5:a9
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.alloc-engine/9984]
ALLOC_ENGINE_V4_OFFER_NEW_LEASE allocation engine will try to offer new lease
to the client [hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9],
tid=0x8c5fb101
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS4 get one host with reservation for subnet
id 2 and IPv4 address 10.148.1.68
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.hosts/9984] HOSTS_CFG_GET_ALL_ADDRESS4
get all hosts with reservations for IPv4 address 10.148.1.68
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_ADDRESS4_COUNT using address 10.148.1.68, found 0 host(s)
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS4_NULL host not found using subnet id 2 and
address 10.148.1.68
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_ADDR4 obtaining IPv4 lease for address 10.148.1.68
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_ADDR4 obtaining IPv4 lease for address 10.148.1.68
2017-10-20 15:05:44.469 INFO [kea-dhcp4.leases/9984] DHCP4_LEASE_ADVERT
[hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9], tid=0x8c5fb101: lease
10.148.1.68 will be advertised
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.options/9984] DHCP4_PACKET_PACK
[hwtype=1 08:00:0f:6c:c5:a9], cid=[no info], tid=0x8c5fb101: preparing on-wire
format of the packet to be sent
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.packets/9984] DHCP4_PACKET_SEND
[hwtype=1 08:00:0f:6c:c5:a9], cid=[no info], tid=0x8c5fb101: trying to send
packet DHCPOFFER (type 2) from 10.151.75.34:67 to 10.148.1.65:67 on interface
eth1
2017-10-20 15:05:44.469 DEBUG [kea-dhcp4.packets/9984] DHCP4_RESPONSE_DATA
[hwtype=1 08:00:0f:6c:c5:a9], cid=[no info], tid=0x8c5fb101: responding with
packet DHCPOFFER (type 2), packet details: local_address=10.151.75.34:67,
remote_address=10.148.1.65:67, msg_type=DHCPOFFER (2), transid=0x8c5fb101,
options:
type=001, len=004: 4294967280 (uint32)
type=003, len=004: 10.148.1.65
type=043, len=065:,
options:
type=001, len=063:
"id:ipphone.mitel.com;sw_tftp=10.151.75.34;call_srv=10.151.75.32" (string)
type=051, len=004: 400 (uint32)
type=053, len=001: 2 (uint8)
type=054, len=004: 10.151.75.34
type=058, len=004: 100 (uint32)
type=059, len=004: 1800 (uint32)
type=066, len=012: "10.151.75.34" (string)
type=128, len=004: 10.151.75.34 (ipv4-address)
type=129, len=004: 10.151.75.32 (ipv4-address)
type=130, len=014: "MITEL IP PHONE" (string)
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.packets/9984] DHCP4_BUFFER_RECEIVED
received buffer from 10.148.1.65:67 to 10.151.75.34:67 over interface eth1
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.options/9984] DHCP4_BUFFER_UNPACK
parsing buffer received from 10.148.1.65 to 10.151.75.34 over interface eth1
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.eval/9984] EVAL_DEBUG_OPTION Pushing
option 60 with value 0x697070686F6E652E6D6974656C2E636F6D00
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.eval/9984] EVAL_DEBUG_STRING Pushing
text string '0'
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.eval/9984] EVAL_DEBUG_STRING Pushing
text string '17'
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.eval/9984] EVAL_DEBUG_SUBSTRING
Popping length 17, start 0, string 0x697070686F6E652E6D6974656C2E636F6D00
pushing result 0x697070686F6E652E6D6974656C2E636F6D
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.eval/9984] EVAL_DEBUG_STRING Pushing
text string 'ipphone.mitel.com'
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.eval/9984] EVAL_DEBUG_EQUAL Popping
0x697070686F6E652E6D6974656C2E636F6D and 0x697070686F6E652E6D6974656C2E636F6D
pushing result 'true'
2017-10-20 15:05:44.475 INFO [kea-dhcp4.options/9984] EVAL_RESULT Expression
mitel evaluated to 1
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.packets/9984] DHCP4_PACKET_RECEIVED
[hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9], tid=0x8c5fb101:
DHCPREQUEST (type 3) received from 10.148.1.65 to 10.151.75.34 on interface eth1
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.packets/9984] DHCP4_QUERY_DATA
[hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9], tid=0x8c5fb101,
packet details: local_address=10.151.75.34:67, remote_address=10.148.1.65:67,
msg_type=DHCPREQUEST (3), transid=0x8c5fb101,
options:
type=050, len=004: 10.148.1.68 (ipv4-address)
type=053, len=001: 3 (uint8)
type=054, len=004: 10.151.75.34 (ipv4-address)
type=055, len=026: 1(uint8) 3(uint8) 6(uint8) 12(uint8) 42(uint8) 44(uint8)
51(uint8) 54(uint8) 58(uint8) 59(uint8) 66(uint8) 120(uint8) 129(uint8)
128(uint8) 130(uint8) 131(uint8) 132(uint8) 133(uint8) 134(uint8) 135(uint8)
224(uint8) 138(uint8) 125(uint8) 43(uint8) 178(uint8) 179(uint8)
type=057, len=002: 1500 (uint16)
type=060, len=018: "ipphone.mitel.com" (string)
type=061, len=007: 01:08:00:0f:6c:c5:a9
type=124, len=5, enterprise id=0x403, data-len0=0, vendor-class-data0=''
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_CFGMGR_SUBNET4_ADDR selected subnet 10.148.1.64/28 for packet received
by matching address 10.148.1.65
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.packets/9984] DHCP4_SUBNET_SELECTED
[hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9], tid=0x8c5fb101: the
subnet with ID 2 was selected for client assignments
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.packets/9984] DHCP4_SUBNET_DATA
[hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9], tid=0x8c5fb101: the
selected subnet details: 10.148.1.64/28
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for
subnet id 2, identified by hwaddr=08000F6CC5A9
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier:
hwaddr=08000F6CC5A9
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier hwaddr=08000F6CC5A9, found
0 host(s)
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 2
and identifier hwaddr=08000F6CC5A9
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for
subnet id 2, identified by client-id=0108000F6CC5A9
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier:
client-id=0108000F6CC5A9
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier client-id=0108000F6CC5A9,
found 0 host(s)
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 2
and identifier client-id=0108000F6CC5A9
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for
subnet id 3, identified by hwaddr=08000F6CC5A9
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier:
hwaddr=08000F6CC5A9
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier hwaddr=08000F6CC5A9, found
0 host(s)
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 3
and identifier hwaddr=08000F6CC5A9
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for
subnet id 3, identified by client-id=0108000F6CC5A9
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier:
client-id=0108000F6CC5A9
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier client-id=0108000F6CC5A9,
found 0 host(s)
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 3
and identifier client-id=0108000F6CC5A9
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for
subnet id 1, identified by hwaddr=08000F6CC5A9
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier:
hwaddr=08000F6CC5A9
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier hwaddr=08000F6CC5A9, found
0 host(s)
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 1
and identifier hwaddr=08000F6CC5A9
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for
subnet id 1, identified by client-id=0108000F6CC5A9
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier:
client-id=0108000F6CC5A9
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier client-id=0108000F6CC5A9,
found 0 host(s)
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 1
and identifier client-id=0108000F6CC5A9
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.dhcp4/9984] DHCP4_CLASS_ASSIGNED
[hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9], tid=0x8c5fb101:
client packet has been assigned to the following class(es):
VENDOR_CLASS_ipphone.mitel.com, mitel
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.ddns/9984]
DHCP4_CLIENT_HOSTNAME_PROCESS [hwtype=1 08:00:0f:6c:c5:a9],
cid=[01:08:00:0f:6c:c5:a9], tid=0x8c5fb101: processing client's Hostname option
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_SUBID_CLIENTID obtaining IPv4 lease for subnet ID 2 and
client ID 01:08:00:0f:6c:c5:a9
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_HWADDR obtaining IPv4 leases for hardware address hwtype=1
08:00:0f:6c:c5:a9
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_SUBID_CLIENTID obtaining IPv4 lease for subnet ID 3 and
client ID 01:08:00:0f:6c:c5:a9
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_HWADDR obtaining IPv4 leases for hardware address hwtype=1
08:00:0f:6c:c5:a9
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_SUBID_CLIENTID obtaining IPv4 lease for subnet ID 1 and
client ID 01:08:00:0f:6c:c5:a9
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_HWADDR obtaining IPv4 leases for hardware address hwtype=1
08:00:0f:6c:c5:a9
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS4 get one host with reservation for subnet
id 2 and IPv4 address 10.148.1.68
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.hosts/9984] HOSTS_CFG_GET_ALL_ADDRESS4
get all hosts with reservations for IPv4 address 10.148.1.68
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_ADDRESS4_COUNT using address 10.148.1.68, found 0 host(s)
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS4_NULL host not found using subnet id 2 and
address 10.148.1.68
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_ADDR4 obtaining IPv4 lease for address 10.148.1.68
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.alloc-engine/9984]
ALLOC_ENGINE_V4_REQUEST_ALLOC_REQUESTED [hwtype=1 08:00:0f:6c:c5:a9],
cid=[01:08:00:0f:6c:c5:a9], tid=0x8c5fb101: trying to allocate requested
address 10.148.1.68
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_ADDR4 obtaining IPv4 lease for address 10.148.1.68
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_ADD_ADDR4 adding IPv4 lease with address 10.148.1.68
2017-10-20 15:05:44.475 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_ADDR4 obtaining IPv4 lease for address 10.148.1.68
2017-10-20 15:05:44.476 INFO [kea-dhcp4.leases/9984] DHCP4_LEASE_ALLOC
[hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9], tid=0x8c5fb101: lease
10.148.1.68 has been allocated
2017-10-20 15:05:44.476 DEBUG [kea-dhcp4.options/9984] DHCP4_PACKET_PACK
[hwtype=1 08:00:0f:6c:c5:a9], cid=[no info], tid=0x8c5fb101: preparing on-wire
format of the packet to be sent
2017-10-20 15:05:44.476 DEBUG [kea-dhcp4.packets/9984] DHCP4_PACKET_SEND
[hwtype=1 08:00:0f:6c:c5:a9], cid=[no info], tid=0x8c5fb101: trying to send
packet DHCPACK (type 5) from 10.151.75.34:67 to 10.148.1.65:67 on interface eth1
2017-10-20 15:05:44.476 DEBUG [kea-dhcp4.packets/9984] DHCP4_RESPONSE_DATA
[hwtype=1 08:00:0f:6c:c5:a9], cid=[no info], tid=0x8c5fb101: responding with
packet DHCPACK (type 5), packet details: local_address=10.151.75.34:67,
remote_address=10.148.1.65:67, msg_type=DHCPACK (5), transid=0x8c5fb101,
options:
type=001, len=004: 4294967280 (uint32)
type=003, len=004: 10.148.1.65
type=043, len=065:,
options:
type=001, len=063:
"id:ipphone.mitel.com;sw_tftp=10.151.75.34;call_srv=10.151.75.32" (string)
type=051, len=004: 400 (uint32)
type=053, len=001: 5 (uint8)
type=054, len=004: 10.151.75.34
type=058, len=004: 100 (uint32)
type=059, len=004: 1800 (uint32)
type=066, len=012: "10.151.75.34" (string)
type=128, len=004: 10.151.75.34 (ipv4-address)
type=129, len=004: 10.151.75.32 (ipv4-address)
type=130, len=014: "MITEL IP PHONE" (string)
So 10.148.1.65 was given out with a 100-second renew-timer
2017-10-20 15:07:26.034 DEBUG [kea-dhcp4.packets/9984] DHCP4_BUFFER_RECEIVED
received buffer from 10.148.1.68:68 to 10.151.75.34:67 over interface eth1
2017-10-20 15:07:26.034 DEBUG [kea-dhcp4.options/9984] DHCP4_BUFFER_UNPACK
parsing buffer received from 10.148.1.68 to 10.151.75.34 over interface eth1
2017-10-20 15:07:26.034 DEBUG [kea-dhcp4.eval/9984] EVAL_DEBUG_OPTION Pushing
option 60 with value 0x697070686F6E652E6D6974656C2E636F6D00
2017-10-20 15:07:26.034 DEBUG [kea-dhcp4.eval/9984] EVAL_DEBUG_STRING Pushing
text string '0'
2017-10-20 15:07:26.034 DEBUG [kea-dhcp4.eval/9984] EVAL_DEBUG_STRING Pushing
text string '17'
2017-10-20 15:07:26.034 DEBUG [kea-dhcp4.eval/9984] EVAL_DEBUG_SUBSTRING
Popping length 17, start 0, string 0x697070686F6E652E6D6974656C2E636F6D00
pushing result 0x697070686F6E652E6D6974656C2E636F6D
2017-10-20 15:07:26.034 DEBUG [kea-dhcp4.eval/9984] EVAL_DEBUG_STRING Pushing
text string 'ipphone.mitel.com'
2017-10-20 15:07:26.034 DEBUG [kea-dhcp4.eval/9984] EVAL_DEBUG_EQUAL Popping
0x697070686F6E652E6D6974656C2E636F6D and 0x697070686F6E652E6D6974656C2E636F6D
pushing result 'true'
2017-10-20 15:07:26.034 INFO [kea-dhcp4.options/9984] EVAL_RESULT Expression
mitel evaluated to 1
2017-10-20 15:07:26.034 DEBUG [kea-dhcp4.packets/9984] DHCP4_PACKET_RECEIVED
[hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9], tid=0x8c5fb101:
DHCPREQUEST (type 3) received from 10.148.1.68 to 10.151.75.34 on interface eth1
2017-10-20 15:07:26.034 DEBUG [kea-dhcp4.packets/9984] DHCP4_QUERY_DATA
[hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9], tid=0x8c5fb101,
packet details: local_address=10.151.75.34:67, remote_address=10.148.1.68:68,
msg_type=DHCPREQUEST (3), transid=0x8c5fb101,
options:
type=053, len=001: 3 (uint8)
type=055, len=026: 1(uint8) 3(uint8) 6(uint8) 12(uint8) 42(uint8) 44(uint8)
51(uint8) 54(uint8) 58(uint8) 59(uint8) 66(uint8) 120(uint8) 129(uint8)
128(uint8) 130(uint8) 131(uint8) 132(uint8) 133(uint8) 134(uint8) 135(uint8)
224(uint8) 138(uint8) 125(uint8) 43(uint8) 178(uint8) 179(uint8)
type=057, len=002: 1500 (uint16)
type=060, len=018: "ipphone.mitel.com" (string)
type=061, len=007: 01:08:00:0f:6c:c5:a9
type=124, len=5, enterprise id=0x403, data-len0=0, vendor-class-data0=''
2017-10-20 15:07:26.034 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_CFGMGR_SUBNET4_ADDR selected subnet 10.148.1.64/28 for packet received
by matching address 10.148.1.68
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.packets/9984] DHCP4_SUBNET_SELECTED
[hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9], tid=0x8c5fb101: the
subnet with ID 2 was selected for client assignments
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.packets/9984] DHCP4_SUBNET_DATA
[hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9], tid=0x8c5fb101: the
selected subnet details: 10.148.1.64/28
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for
subnet id 2, identified by hwaddr=08000F6CC5A9
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier:
hwaddr=08000F6CC5A9
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier hwaddr=08000F6CC5A9, found
0 host(s)
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 2
and identifier hwaddr=08000F6CC5A9
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for
subnet id 2, identified by client-id=0108000F6CC5A9
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier:
client-id=0108000F6CC5A9
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier client-id=0108000F6CC5A9,
found 0 host(s)
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 2
and identifier client-id=0108000F6CC5A9
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for
subnet id 3, identified by hwaddr=08000F6CC5A9
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier:
hwaddr=08000F6CC5A9
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier hwaddr=08000F6CC5A9, found
0 host(s)
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 3
and identifier hwaddr=08000F6CC5A9
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for
subnet id 3, identified by client-id=0108000F6CC5A9
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier:
client-id=0108000F6CC5A9
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier client-id=0108000F6CC5A9,
found 0 host(s)
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 3
and identifier client-id=0108000F6CC5A9
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for
subnet id 1, identified by hwaddr=08000F6CC5A9
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier:
hwaddr=08000F6CC5A9
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier hwaddr=08000F6CC5A9, found
0 host(s)
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 1
and identifier hwaddr=08000F6CC5A9
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for
subnet id 1, identified by client-id=0108000F6CC5A9
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier:
client-id=0108000F6CC5A9
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier client-id=0108000F6CC5A9,
found 0 host(s)
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 1
and identifier client-id=0108000F6CC5A9
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.dhcp4/9984] DHCP4_CLASS_ASSIGNED
[hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9], tid=0x8c5fb101:
client packet has been assigned to the following class(es):
VENDOR_CLASS_ipphone.mitel.com, mitel
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.ddns/9984]
DHCP4_CLIENT_HOSTNAME_PROCESS [hwtype=1 08:00:0f:6c:c5:a9],
cid=[01:08:00:0f:6c:c5:a9], tid=0x8c5fb101: processing client's Hostname option
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_SUBID_CLIENTID obtaining IPv4 lease for subnet ID 2 and
client ID 01:08:00:0f:6c:c5:a9
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_SUBID_CLIENTID obtaining IPv4 lease for subnet ID 3 and
client ID 01:08:00:0f:6c:c5:a9
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_HWADDR obtaining IPv4 leases for hardware address hwtype=1
08:00:0f:6c:c5:a9
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_SUBID_CLIENTID obtaining IPv4 lease for subnet ID 1 and
client ID 01:08:00:0f:6c:c5:a9
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_HWADDR obtaining IPv4 leases for hardware address hwtype=1
08:00:0f:6c:c5:a9
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS4 get one host with reservation for subnet
id 2 and IPv4 address 10.148.1.68
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.hosts/9984] HOSTS_CFG_GET_ALL_ADDRESS4
get all hosts with reservations for IPv4 address 10.148.1.68
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_ADDRESS4_COUNT using address 10.148.1.68, found 0 host(s)
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS4_NULL host not found using subnet id 2 and
address 10.148.1.68
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_ADDR4 obtaining IPv4 lease for address 10.148.1.68
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.alloc-engine/9984]
ALLOC_ENGINE_V4_REQUEST_ALLOC_REQUESTED [hwtype=1 08:00:0f:6c:c5:a9],
cid=[01:08:00:0f:6c:c5:a9], tid=0x8c5fb101: trying to allocate requested
address 10.148.1.68
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_ADDR4 obtaining IPv4 lease for address 10.148.1.68
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.bad-packets/9984]
DHCP4_PACKET_NAK_0004 [hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9],
tid=0x8c5fb101: failed to grant a lease, client sent ciaddr 10.148.1.68,
requested-ip-address (no address)
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.options/9984] DHCP4_PACKET_PACK
[hwtype=1 08:00:0f:6c:c5:a9], cid=[no info], tid=0x8c5fb101: preparing on-wire
format of the packet to be sent
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.packets/9984] DHCP4_PACKET_SEND
[hwtype=1 08:00:0f:6c:c5:a9], cid=[no info], tid=0x8c5fb101: trying to send
packet DHCPNAK (type 6) from 10.151.75.34:67 to 10.148.1.68:68 on interface eth1
2017-10-20 15:07:26.035 DEBUG [kea-dhcp4.packets/9984] DHCP4_RESPONSE_DATA
[hwtype=1 08:00:0f:6c:c5:a9], cid=[no info], tid=0x8c5fb101: responding with
packet DHCPNAK (type 6), packet details: local_address=10.151.75.34:67,
remote_address=10.148.1.68:68, msg_type=DHCPNAK (6), transid=0x8c5fb101,
options:
type=053, len=001: 6 (uint8)
type=054, len=004: 10.151.75.34
???
2017-10-20 15:08:16.108 DEBUG [kea-dhcp4.packets/9984] DHCP4_BUFFER_RECEIVED
received buffer from 10.148.1.65:67 to 10.151.75.34:67 over interface eth1
2017-10-20 15:08:16.108 DEBUG [kea-dhcp4.options/9984] DHCP4_BUFFER_UNPACK
parsing buffer received from 10.148.1.65 to 10.151.75.34 over interface eth1
2017-10-20 15:08:16.108 DEBUG [kea-dhcp4.eval/9984] EVAL_DEBUG_OPTION Pushing
option 60 with value 0x697070686F6E652E6D6974656C2E636F6D00
2017-10-20 15:08:16.108 DEBUG [kea-dhcp4.eval/9984] EVAL_DEBUG_STRING Pushing
text string '0'
2017-10-20 15:08:16.108 DEBUG [kea-dhcp4.eval/9984] EVAL_DEBUG_STRING Pushing
text string '17'
2017-10-20 15:08:16.108 DEBUG [kea-dhcp4.eval/9984] EVAL_DEBUG_SUBSTRING
Popping length 17, start 0, string 0x697070686F6E652E6D6974656C2E636F6D00
pushing result 0x697070686F6E652E6D6974656C2E636F6D
2017-10-20 15:08:16.108 DEBUG [kea-dhcp4.eval/9984] EVAL_DEBUG_STRING Pushing
text string 'ipphone.mitel.com'
2017-10-20 15:08:16.108 DEBUG [kea-dhcp4.eval/9984] EVAL_DEBUG_EQUAL Popping
0x697070686F6E652E6D6974656C2E636F6D and 0x697070686F6E652E6D6974656C2E636F6D
pushing result 'true'
2017-10-20 15:08:16.108 INFO [kea-dhcp4.options/9984] EVAL_RESULT Expression
mitel evaluated to 1
2017-10-20 15:08:16.108 DEBUG [kea-dhcp4.packets/9984] DHCP4_PACKET_RECEIVED
[hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9], tid=0x76d11804:
DHCPDISCOVER (type 1) received from 10.148.1.65 to 10.151.75.34 on interface
eth1
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.packets/9984] DHCP4_QUERY_DATA
[hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9], tid=0x76d11804,
packet details: local_address=10.151.75.34:67, remote_address=10.148.1.65:67,
msg_type=DHCPDISCOVER (1), transid=0x76d11804,
options:
type=053, len=001: 1 (uint8)
type=055, len=026: 1(uint8) 3(uint8) 6(uint8) 12(uint8) 42(uint8) 44(uint8)
51(uint8) 54(uint8) 58(uint8) 59(uint8) 66(uint8) 120(uint8) 129(uint8)
128(uint8) 130(uint8) 131(uint8) 132(uint8) 133(uint8) 134(uint8) 135(uint8)
224(uint8) 138(uint8) 125(uint8) 43(uint8) 178(uint8) 179(uint8)
type=057, len=002: 1500 (uint16)
type=060, len=018: "ipphone.mitel.com" (string)
type=061, len=007: 01:08:00:0f:6c:c5:a9
type=124, len=5, enterprise id=0x403, data-len0=0, vendor-class-data0=''
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_CFGMGR_SUBNET4_ADDR selected subnet 10.148.1.64/28 for packet received
by matching address 10.148.1.65
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.packets/9984] DHCP4_SUBNET_SELECTED
[hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9], tid=0x76d11804: the
subnet with ID 2 was selected for client assignments
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.packets/9984] DHCP4_SUBNET_DATA
[hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9], tid=0x76d11804: the
selected subnet details: 10.148.1.64/28
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for
subnet id 2, identified by hwaddr=08000F6CC5A9
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier:
hwaddr=08000F6CC5A9
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier hwaddr=08000F6CC5A9, found
0 host(s)
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 2
and identifier hwaddr=08000F6CC5A9
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for
subnet id 2, identified by client-id=0108000F6CC5A9
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier:
client-id=0108000F6CC5A9
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier client-id=0108000F6CC5A9,
found 0 host(s)
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 2
and identifier client-id=0108000F6CC5A9
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for
subnet id 3, identified by hwaddr=08000F6CC5A9
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier:
hwaddr=08000F6CC5A9
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier hwaddr=08000F6CC5A9, found
0 host(s)
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 3
and identifier hwaddr=08000F6CC5A9
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for
subnet id 3, identified by client-id=0108000F6CC5A9
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier:
client-id=0108000F6CC5A9
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier client-id=0108000F6CC5A9,
found 0 host(s)
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 3
and identifier client-id=0108000F6CC5A9
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for
subnet id 1, identified by hwaddr=08000F6CC5A9
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier:
hwaddr=08000F6CC5A9
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier hwaddr=08000F6CC5A9, found
0 host(s)
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 1
and identifier hwaddr=08000F6CC5A9
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for
subnet id 1, identified by client-id=0108000F6CC5A9
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier:
client-id=0108000F6CC5A9
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier client-id=0108000F6CC5A9,
found 0 host(s)
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 1
and identifier client-id=0108000F6CC5A9
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.dhcp4/9984] DHCP4_CLASS_ASSIGNED
[hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9], tid=0x76d11804:
client packet has been assigned to the following class(es):
VENDOR_CLASS_ipphone.mitel.com, mitel
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.ddns/9984]
DHCP4_CLIENT_HOSTNAME_PROCESS [hwtype=1 08:00:0f:6c:c5:a9],
cid=[01:08:00:0f:6c:c5:a9], tid=0x76d11804: processing client's Hostname option
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_SUBID_CLIENTID obtaining IPv4 lease for subnet ID 2 and
client ID 01:08:00:0f:6c:c5:a9
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_SUBID_CLIENTID obtaining IPv4 lease for subnet ID 3 and
client ID 01:08:00:0f:6c:c5:a9
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_HWADDR obtaining IPv4 leases for hardware address hwtype=1
08:00:0f:6c:c5:a9
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_SUBID_CLIENTID obtaining IPv4 lease for subnet ID 1 and
client ID 01:08:00:0f:6c:c5:a9
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_HWADDR obtaining IPv4 leases for hardware address hwtype=1
08:00:0f:6c:c5:a9
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.alloc-engine/9984]
ALLOC_ENGINE_V4_OFFER_NEW_LEASE allocation engine will try to offer new lease
to the client [hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9],
tid=0x76d11804
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS4 get one host with reservation for subnet
id 2 and IPv4 address 10.148.1.69
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.hosts/9984] HOSTS_CFG_GET_ALL_ADDRESS4
get all hosts with reservations for IPv4 address 10.148.1.69
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_ADDRESS4_COUNT using address 10.148.1.69, found 0 host(s)
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS4_NULL host not found using subnet id 2 and
address 10.148.1.69
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_ADDR4 obtaining IPv4 lease for address 10.148.1.69
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_ADDR4 obtaining IPv4 lease for address 10.148.1.69
2017-10-20 15:08:16.109 INFO [kea-dhcp4.leases/9984] DHCP4_LEASE_ADVERT
[hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9], tid=0x76d11804: lease
10.148.1.69 will be advertised
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.options/9984] DHCP4_PACKET_PACK
[hwtype=1 08:00:0f:6c:c5:a9], cid=[no info], tid=0x76d11804: preparing on-wire
format of the packet to be sent
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.packets/9984] DHCP4_PACKET_SEND
[hwtype=1 08:00:0f:6c:c5:a9], cid=[no info], tid=0x76d11804: trying to send
packet DHCPOFFER (type 2) from 10.151.75.34:67 to 10.148.1.65:67 on interface
eth1
2017-10-20 15:08:16.109 DEBUG [kea-dhcp4.packets/9984] DHCP4_RESPONSE_DATA
[hwtype=1 08:00:0f:6c:c5:a9], cid=[no info], tid=0x76d11804: responding with
packet DHCPOFFER (type 2), packet details: local_address=10.151.75.34:67,
remote_address=10.148.1.65:67, msg_type=DHCPOFFER (2), transid=0x76d11804,
options:
type=001, len=004: 4294967280 (uint32)
type=003, len=004: 10.148.1.65
type=043, len=065:,
options:
type=001, len=063:
"id:ipphone.mitel.com;sw_tftp=10.151.75.34;call_srv=10.151.75.32" (string)
type=051, len=004: 400 (uint32)
type=053, len=001: 2 (uint8)
type=054, len=004: 10.151.75.34
type=058, len=004: 100 (uint32)
type=059, len=004: 1800 (uint32)
type=066, len=012: "10.151.75.34" (string)
type=128, len=004: 10.151.75.34 (ipv4-address)
type=129, len=004: 10.151.75.32 (ipv4-address)
type=130, len=014: "MITEL IP PHONE" (string)
2017-10-20 15:08:16.121 DEBUG [kea-dhcp4.packets/9984] DHCP4_BUFFER_RECEIVED
received buffer from 10.148.1.65:67 to 10.151.75.34:67 over interface eth1
2017-10-20 15:08:16.121 DEBUG [kea-dhcp4.options/9984] DHCP4_BUFFER_UNPACK
parsing buffer received from 10.148.1.65 to 10.151.75.34 over interface eth1
2017-10-20 15:08:16.121 DEBUG [kea-dhcp4.eval/9984] EVAL_DEBUG_OPTION Pushing
option 60 with value 0x697070686F6E652E6D6974656C2E636F6D00
2017-10-20 15:08:16.121 DEBUG [kea-dhcp4.eval/9984] EVAL_DEBUG_STRING Pushing
text string '0'
2017-10-20 15:08:16.121 DEBUG [kea-dhcp4.eval/9984] EVAL_DEBUG_STRING Pushing
text string '17'
2017-10-20 15:08:16.121 DEBUG [kea-dhcp4.eval/9984] EVAL_DEBUG_SUBSTRING
Popping length 17, start 0, string 0x697070686F6E652E6D6974656C2E636F6D00
pushing result 0x697070686F6E652E6D6974656C2E636F6D
2017-10-20 15:08:16.121 DEBUG [kea-dhcp4.eval/9984] EVAL_DEBUG_STRING Pushing
text string 'ipphone.mitel.com'
2017-10-20 15:08:16.121 DEBUG [kea-dhcp4.eval/9984] EVAL_DEBUG_EQUAL Popping
0x697070686F6E652E6D6974656C2E636F6D and 0x697070686F6E652E6D6974656C2E636F6D
pushing result 'true'
2017-10-20 15:08:16.121 INFO [kea-dhcp4.options/9984] EVAL_RESULT Expression
mitel evaluated to 1
2017-10-20 15:08:16.121 DEBUG [kea-dhcp4.packets/9984] DHCP4_PACKET_RECEIVED
[hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9], tid=0x76d11804:
DHCPREQUEST (type 3) received from 10.148.1.65 to 10.151.75.34 on interface eth1
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.packets/9984] DHCP4_QUERY_DATA
[hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9], tid=0x76d11804,
packet details: local_address=10.151.75.34:67, remote_address=10.148.1.65:67,
msg_type=DHCPREQUEST (3), transid=0x76d11804,
options:
type=050, len=004: 10.148.1.69 (ipv4-address)
type=053, len=001: 3 (uint8)
type=054, len=004: 10.151.75.34 (ipv4-address)
type=055, len=026: 1(uint8) 3(uint8) 6(uint8) 12(uint8) 42(uint8) 44(uint8)
51(uint8) 54(uint8) 58(uint8) 59(uint8) 66(uint8) 120(uint8) 129(uint8)
128(uint8) 130(uint8) 131(uint8) 132(uint8) 133(uint8) 134(uint8) 135(uint8)
224(uint8) 138(uint8) 125(uint8) 43(uint8) 178(uint8) 179(uint8)
type=057, len=002: 1500 (uint16)
type=060, len=018: "ipphone.mitel.com" (string)
type=061, len=007: 01:08:00:0f:6c:c5:a9
type=124, len=5, enterprise id=0x403, data-len0=0, vendor-class-data0=''
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_CFGMGR_SUBNET4_ADDR selected subnet 10.148.1.64/28 for packet received
by matching address 10.148.1.65
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.packets/9984] DHCP4_SUBNET_SELECTED
[hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9], tid=0x76d11804: the
subnet with ID 2 was selected for client assignments
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.packets/9984] DHCP4_SUBNET_DATA
[hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9], tid=0x76d11804: the
selected subnet details: 10.148.1.64/28
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for
subnet id 2, identified by hwaddr=08000F6CC5A9
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier:
hwaddr=08000F6CC5A9
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier hwaddr=08000F6CC5A9, found
0 host(s)
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 2
and identifier hwaddr=08000F6CC5A9
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for
subnet id 2, identified by client-id=0108000F6CC5A9
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier:
client-id=0108000F6CC5A9
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier client-id=0108000F6CC5A9,
found 0 host(s)
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 2
and identifier client-id=0108000F6CC5A9
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for
subnet id 3, identified by hwaddr=08000F6CC5A9
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier:
hwaddr=08000F6CC5A9
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier hwaddr=08000F6CC5A9, found
0 host(s)
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 3
and identifier hwaddr=08000F6CC5A9
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for
subnet id 3, identified by client-id=0108000F6CC5A9
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier:
client-id=0108000F6CC5A9
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier client-id=0108000F6CC5A9,
found 0 host(s)
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 3
and identifier client-id=0108000F6CC5A9
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for
subnet id 1, identified by hwaddr=08000F6CC5A9
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier:
hwaddr=08000F6CC5A9
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier hwaddr=08000F6CC5A9, found
0 host(s)
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 1
and identifier hwaddr=08000F6CC5A9
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for
subnet id 1, identified by client-id=0108000F6CC5A9
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier:
client-id=0108000F6CC5A9
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier client-id=0108000F6CC5A9,
found 0 host(s)
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 1
and identifier client-id=0108000F6CC5A9
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.dhcp4/9984] DHCP4_CLASS_ASSIGNED
[hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9], tid=0x76d11804:
client packet has been assigned to the following class(es):
VENDOR_CLASS_ipphone.mitel.com, mitel
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.ddns/9984]
DHCP4_CLIENT_HOSTNAME_PROCESS [hwtype=1 08:00:0f:6c:c5:a9],
cid=[01:08:00:0f:6c:c5:a9], tid=0x76d11804: processing client's Hostname option
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_SUBID_CLIENTID obtaining IPv4 lease for subnet ID 2 and
client ID 01:08:00:0f:6c:c5:a9
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_SUBID_CLIENTID obtaining IPv4 lease for subnet ID 3 and
client ID 01:08:00:0f:6c:c5:a9
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_HWADDR obtaining IPv4 leases for hardware address hwtype=1
08:00:0f:6c:c5:a9
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_SUBID_CLIENTID obtaining IPv4 lease for subnet ID 1 and
client ID 01:08:00:0f:6c:c5:a9
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_HWADDR obtaining IPv4 leases for hardware address hwtype=1
08:00:0f:6c:c5:a9
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS4 get one host with reservation for subnet
id 2 and IPv4 address 10.148.1.69
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.hosts/9984] HOSTS_CFG_GET_ALL_ADDRESS4
get all hosts with reservations for IPv4 address 10.148.1.69
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ALL_ADDRESS4_COUNT using address 10.148.1.69, found 0 host(s)
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.hosts/9984]
HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS4_NULL host not found using subnet id 2 and
address 10.148.1.69
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_ADDR4 obtaining IPv4 lease for address 10.148.1.69
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.alloc-engine/9984]
ALLOC_ENGINE_V4_REQUEST_ALLOC_REQUESTED [hwtype=1 08:00:0f:6c:c5:a9],
cid=[01:08:00:0f:6c:c5:a9], tid=0x76d11804: trying to allocate requested
address 10.148.1.69
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_ADDR4 obtaining IPv4 lease for address 10.148.1.69
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_ADD_ADDR4 adding IPv4 lease with address 10.148.1.69
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_GET_ADDR4 obtaining IPv4 lease for address 10.148.1.69
2017-10-20 15:08:16.122 INFO [kea-dhcp4.leases/9984] DHCP4_LEASE_ALLOC
[hwtype=1 08:00:0f:6c:c5:a9], cid=[01:08:00:0f:6c:c5:a9], tid=0x76d11804: lease
10.148.1.69 has been allocated
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.options/9984] DHCP4_PACKET_PACK
[hwtype=1 08:00:0f:6c:c5:a9], cid=[no info], tid=0x76d11804: preparing on-wire
format of the packet to be sent
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.packets/9984] DHCP4_PACKET_SEND
[hwtype=1 08:00:0f:6c:c5:a9], cid=[no info], tid=0x76d11804: trying to send
packet DHCPACK (type 5) from 10.151.75.34:67 to 10.148.1.65:67 on interface eth1
2017-10-20 15:08:16.122 DEBUG [kea-dhcp4.packets/9984] DHCP4_RESPONSE_DATA
[hwtype=1 08:00:0f:6c:c5:a9], cid=[no info], tid=0x76d11804: responding with
packet DHCPACK (type 5), packet details: local_address=10.151.75.34:67,
remote_address=10.148.1.65:67, msg_type=DHCPACK (5), transid=0x76d11804,
options:
type=001, len=004: 4294967280 (uint32)
type=003, len=004: 10.148.1.65
type=043, len=065:,
options:
type=001, len=063:
"id:ipphone.mitel.com;sw_tftp=10.151.75.34;call_srv=10.151.75.32" (string)
type=051, len=004: 400 (uint32)
type=053, len=001: 5 (uint8)
type=054, len=004: 10.151.75.34
type=058, len=004: 100 (uint32)
type=059, len=004: 1800 (uint32)
type=066, len=012: "10.151.75.34" (string)
type=128, len=004: 10.151.75.34 (ipv4-address)
type=129, len=004: 10.151.75.32 (ipv4-address)
type=130, len=014: "MITEL IP PHONE" (string)
^C2017-10-20 15:08:25.011 DEBUG [kea-dhcp4.packets/9984]
DHCP4_BUFFER_WAIT_SIGNAL signal received while waiting for next packet, next
waiting signal is 2
2017-10-20 15:08:25.011 DEBUG [kea-dhcp4.dhcp4/9984] DHCP4_COMMAND_RECEIVED
received command shutdown, arguments: (none)
2017-10-20 15:08:25.011 DEBUG [kea-dhcp4.dhcp4/9984] DHCP4_SHUTDOWN_REQUEST
shutdown of server requested
2017-10-20 15:08:25.011 INFO [kea-dhcp4.dhcp4/9984] DHCP4_SHUTDOWN server
shutdown
2017-10-20 15:08:25.011 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_TIMERMGR_UNREGISTER_ALL_TIMERS unregistering all timers
2017-10-20 15:08:25.012 DEBUG [kea-dhcp4.commands/9984] COMMAND_DEREGISTERED
Command build-report deregistered
2017-10-20 15:08:25.012 DEBUG [kea-dhcp4.commands/9984] COMMAND_DEREGISTERED
Command config-get deregistered
2017-10-20 15:08:25.012 DEBUG [kea-dhcp4.commands/9984] COMMAND_DEREGISTERED
Command config-reload deregistered
2017-10-20 15:08:25.012 DEBUG [kea-dhcp4.commands/9984] COMMAND_DEREGISTERED
Command config-test deregistered
2017-10-20 15:08:25.012 DEBUG [kea-dhcp4.commands/9984] COMMAND_DEREGISTERED
Command config-write deregistered
2017-10-20 15:08:25.012 DEBUG [kea-dhcp4.commands/9984] COMMAND_DEREGISTERED
Command leases-reclaim deregistered
2017-10-20 15:08:25.012 DEBUG [kea-dhcp4.commands/9984] COMMAND_DEREGISTERED
Command libreload deregistered
2017-10-20 15:08:25.012 DEBUG [kea-dhcp4.commands/9984] COMMAND_DEREGISTERED
Command config-set deregistered
2017-10-20 15:08:25.012 DEBUG [kea-dhcp4.commands/9984] COMMAND_DEREGISTERED
Command shutdown deregistered
2017-10-20 15:08:25.012 DEBUG [kea-dhcp4.commands/9984] COMMAND_DEREGISTERED
Command statistic-get deregistered
2017-10-20 15:08:25.012 DEBUG [kea-dhcp4.commands/9984] COMMAND_DEREGISTERED
Command statistic-get-all deregistered
2017-10-20 15:08:25.012 DEBUG [kea-dhcp4.commands/9984] COMMAND_DEREGISTERED
Command statistic-remove deregistered
2017-10-20 15:08:25.012 DEBUG [kea-dhcp4.commands/9984] COMMAND_DEREGISTERED
Command statistic-remove-all deregistered
2017-10-20 15:08:25.012 DEBUG [kea-dhcp4.commands/9984] COMMAND_DEREGISTERED
Command statistic-reset deregistered
2017-10-20 15:08:25.012 DEBUG [kea-dhcp4.commands/9984] COMMAND_DEREGISTERED
Command statistic-reset-all deregistered
2017-10-20 15:08:25.012 DEBUG [kea-dhcp4.commands/9984] COMMAND_DEREGISTERED
Command version-get deregistered
2017-10-20 15:08:25.023 DEBUG [kea-dhcp4.dhcpsrv/9984] DHCPSRV_CLOSE_DB closing
currently open memfile database
2017-10-20 15:08:25.023 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_TIMERMGR_UNREGISTER_TIMER unregistering timer: memfile-lfc
2017-10-20 15:08:25.023 DEBUG [kea-dhcp4.dhcpsrv/9984]
DHCPSRV_MEMFILE_LFC_UNREGISTER_TIMER_FAILED failed to unregister timer
'memfile-lfc': unable to unregister non existing timer 'memfile-lfc'
1;31mroot@vlab275n0# 0mexit
exit
Script done on Fri Oct 20 15:08:31 2017
kea_sles12_nak.pcap
Description: kea_sles12_nak.pcap
_______________________________________________ Kea-users mailing list [email protected] https://lists.isc.org/mailman/listinfo/kea-users
