[Kea-users] Issue with DDNS updates
David, Marcos
marcos.david at sky.uk
Wed Sep 27 12:48:54 UTC 2017
Hi,
Thank you for looking into this.
Here is a full log of a request and the renew.
I just took out some of the expired leases processing messages to keep the logs shorter. The config is the same.
2017-09-27 12:18:32.054 DEBUG [kea-dhcp4.dhcp4/21] DHCP4_START_INFO pid: 21, port: 67, verbose: yes
2017-09-27 12:18:32.054 INFO [kea-dhcp4.dhcp4/21] DHCP4_STARTING Kea DHCPv4 server version 1.1.0 starting
2017-09-27 12:18:32.054 DEBUG [kea-dhcp4.dhcp4/21] DHCP4_OPEN_SOCKET opening sockets on port 67
2017-09-27 12:18:32.054 DEBUG [kea-dhcp4.commands/21] COMMAND_REGISTERED Command list-commands registered
2017-09-27 12:18:32.054 DEBUG [kea-dhcp4.commands/21] COMMAND_REGISTERED Command shutdown registered
2017-09-27 12:18:32.054 DEBUG [kea-dhcp4.commands/21] COMMAND_REGISTERED Command leases-reclaim registered
2017-09-27 12:18:32.054 DEBUG [kea-dhcp4.commands/21] COMMAND_REGISTERED Command statistic-get registered
2017-09-27 12:18:32.054 DEBUG [kea-dhcp4.commands/21] COMMAND_REGISTERED Command statistic-reset registered
2017-09-27 12:18:32.054 DEBUG [kea-dhcp4.commands/21] COMMAND_REGISTERED Command statistic-remove registered
2017-09-27 12:18:32.054 DEBUG [kea-dhcp4.commands/21] COMMAND_REGISTERED Command statistic-get-all registered
2017-09-27 12:18:32.054 DEBUG [kea-dhcp4.commands/21] COMMAND_REGISTERED Command statistic-reset-all registered
2017-09-27 12:18:32.054 DEBUG [kea-dhcp4.commands/21] COMMAND_REGISTERED Command statistic-remove-all registered
2017-09-27 12:18:32.054 INFO [kea-dhcp-ddns.dhcpddns/19] DHCP_DDNS_STARTING DHCP-DDNS starting, pid: 19, version: 1.1.0
2017-09-27 12:18:32.054 DEBUG [kea-dhcp-ddns.dhcpddns/19] DCTL_INIT_PROCESS DhcpDdns initializing the application
2017-09-27 12:18:32.054 DEBUG [kea-dhcp-ddns.dhcpddns/19] DCTL_STANDALONE DhcpDdns skipping message queue, running standalone
2017-09-27 12:18:32.055 DEBUG [kea-dhcp4.dhcp4/21] DHCP4_COMMAND_RECEIVED received command config-reload, arguments: { "dhcp-ddns": { "enable-updates": true, "generated-prefix": "pre", "qualifying-suffix": "stagingdhcp.example.com.", "replace-client-name": "always" }, "expired-leases-processing": { "flush-reclaimed-timer-wait-time": 25, "hold-reclaimed-time": 360, "max-reclaim-leases": 100, "max-reclaim-time": 250, "reclaim-timer-wait-time": 10, "unwarned-reclaim-cycles": 5 }, "hosts-database": { "host": "0.0.0.0", "name": "keadb", "password": "password", "type": "mysql", "user": "kea" }, "interfaces-config": { "interfaces": [ "eth0" ] }, "lease-database": { "host": "0.0.0.0", "name": "keadb", "password": "password", "type": "mysql", "user": "kea" }, "option-data": [ { "code": 15, "csv-format": true, "data": "stagingdhcp.example.com", "name": "domain-name", "space": "dhcp4" }, { "code": 6, "csv-format": true, "data": "10.93.211.10,10.93.211.11", "name": "domain-name-servers", "space": "dhcp4" }, { "code": 42, "csv-format": true, "data": "10.254.254.254,10.254.191.254,10.254.60.250,10.254.191.250", "name": "ntp-servers", "space": "dhcp4" }, { "code": 3, "csv-format": true, "data": "10.93.211.254", "name": "routers", "space": "dhcp4" } ], "subnet4": [ { "id": 351, "pools": [ { "pool": "10.93.208.1-10.93.208.253" } ], "subnet": "10.93.208.0/22" } ], "valid-lifetime": 300 }
2017-09-27 12:18:32.055 DEBUG [kea-dhcp4.dhcp4/21] DHCP4_CONFIG_RECEIVED received configuration { "dhcp-ddns": { "enable-updates": true, "generated-prefix": "pre", "qualifying-suffix": "stagingdhcp.example.com.", "replace-client-name": "always" }, "expired-leases-processing": { "flush-reclaimed-timer-wait-time": 25, "hold-reclaimed-time": 360, "max-reclaim-leases": 100, "max-reclaim-time": 250, "reclaim-timer-wait-time": 10, "unwarned-reclaim-cycles": 5 }, "hosts-database": { "host": "0.0.0.0", "name": "keadb", "password": "password", "type": "mysql", "user": "kea" }, "interfaces-config": { "interfaces": [ "eth0" ] }, "lease-database": { "host": "0.0.0.0", "name": "keadb", "password": "password", "type": "mysql", "user": "kea" }, "option-data": [ { "code": 15, "csv-format": true, "data": "stagingdhcp.example.com", "name": "domain-name", "space": "dhcp4" }, { "code": 6, "csv-format": true, "data": "10.93.211.10,10.93.211.11", "name": "domain-name-servers", "space": "dhcp4" }, { "code": 42, "csv-format": true, "data": "10.254.254.254,10.254.191.254,10.254.60.250,10.254.191.250", "name": "ntp-servers", "space": "dhcp4" }, { "code": 3, "csv-format": true, "data": "10.93.211.254", "name": "routers", "space": "dhcp4" } ], "subnet4": [ { "id": 351, "pools": [ { "pool": "10.93.208.1-10.93.208.253" } ], "subnet": "10.93.208.0/22" } ], "valid-lifetime": 300 }
2017-09-27 12:18:32.055 DEBUG [kea-dhcp-ddns.dhcpddns/19] DHCP_DDNS_CONFIGURE configuration update received: { "dns-server-timeout": 500, "forward-ddns": { "ddns-domains": [ { "dns-servers": [ { "ip-address": "10.93.211.12" } ], "key-name": "tsig-dhcpupdate", "name": "stagingdhcp.example.com." } ] }, "ip-address": "127.0.0.1", "port": 53001, "reverse-ddns": { "ddns-domains": [ { "dns-servers": [ { "ip-address": "10.93.211.12" } ], "key-name": "tsig-dhcpupdate", "name": "93.10.in-addr.arpa." } ] }, "tsig-keys": [ { "algorithm": "HMAC-SHA256", "name": "tsig-dhcpupdate", "secret": "secret" } ] }
2017-09-27 12:18:32.055 DEBUG [kea-dhcp-ddns.dhcpddns/19] DCTL_CONFIG_START parsing new configuration: { "dns-server-timeout": 500, "forward-ddns": { "ddns-domains": [ { "dns-servers": [ { "ip-address": "10.93.211.12" } ], "key-name": "tsig-dhcpupdate", "name": "stagingdhcp.example.com." } ] }, "ip-address": "127.0.0.1", "port": 53001, "reverse-ddns": { "ddns-domains": [ { "dns-servers": [ { "ip-address": "10.93.211.12" } ], "key-name": "tsig-dhcpupdate", "name": "93.10.in-addr.arpa." } ] }, "tsig-keys": [ { "algorithm": "HMAC-SHA256", "name": "tsig-dhcpupdate", "secret": "secret" } ] }
2017-09-27 12:18:32.055 INFO [kea-dhcp-ddns.dhcpddns/19] DCTL_CONFIG_COMPLETE server has completed configuration: listening on 127.0.0.1, port 53001, using UDP
2017-09-27 12:18:32.055 DEBUG [kea-dhcp-ddns.dhcpddns/19] DCTL_RUN_PROCESS DhcpDdns starting application event loop
2017-09-27 12:18:32.055 INFO [kea-dhcp-ddns.dhcpddns/19] DHCP_DDNS_STARTED Kea DHCP-DDNS server version 1.1.0 started
2017-09-27 12:18:32.055 DEBUG [kea-dhcp-ddns.dhcpddns/19] DHCP_DDNS_QUEUE_MGR_RECONFIGURING application is reconfiguring the queue manager
2017-09-27 12:18:32.055 DEBUG [kea-dhcp-ddns.dhcpddns/19] DHCP_DDNS_QUEUE_MGR_STARTED application's queue manager has begun listening for requests.
2017-09-27 12:18:32.056 DEBUG [kea-dhcp4.dhcp4/21] DHCP4_CONFIG_START DHCPv4 server is processing the following configuration: { "dhcp-ddns": { "enable-updates": true, "generated-prefix": "pre", "qualifying-suffix": "stagingdhcp.example.com.", "replace-client-name": "always" }, "expired-leases-processing": { "flush-reclaimed-timer-wait-time": 25, "hold-reclaimed-time": 360, "max-reclaim-leases": 100, "max-reclaim-time": 250, "reclaim-timer-wait-time": 10, "unwarned-reclaim-cycles": 5 }, "hosts-database": { "host": "0.0.0.0", "name": "keadb", "password": "password", "type": "mysql", "user": "kea" }, "interfaces-config": { "interfaces": [ "eth0" ] }, "lease-database": { "host": "0.0.0.0", "name": "keadb", "password": "password", "type": "mysql", "user": "kea" }, "option-data": [ { "code": 15, "csv-format": true, "data": "stagingdhcp.example.com", "name": "domain-name", "space": "dhcp4" }, { "code": 6, "csv-format": true, "data": "10.93.211.10,10.93.211.11", "name": "domain-name-servers", "space": "dhcp4" }, { "code": 42, "csv-format": true, "data": "10.254.254.254,10.254.191.254,10.254.60.250,10.254.191.250", "name": "ntp-servers", "space": "dhcp4" }, { "code": 3, "csv-format": true, "data": "10.93.211.254", "name": "routers", "space": "dhcp4" } ], "subnet4": [ { "id": 351, "pools": [ { "pool": "10.93.208.1-10.93.208.253" } ], "subnet": "10.93.208.0/22" } ], "valid-lifetime": 300 }
2017-09-27 12:18:32.056 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_TIMERMGR_UNREGISTER_ALL_TIMERS unregistering all timers
2017-09-27 12:18:32.056 DEBUG [kea-dhcp4.dhcp4/21] DHCP4_PARSER_CREATED created parser for configuration element dhcp-ddns
2017-09-27 12:18:32.056 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_CFGMGR_CFG_DHCP_DDNS Setting DHCP-DDNS configuration to: DHCP_DDNS updates enabled
2017-09-27 12:18:32.056 DEBUG [kea-dhcp4.dhcp4/21] DHCP4_PARSER_CREATED created parser for configuration element expired-leases-processing
2017-09-27 12:18:32.056 DEBUG [kea-dhcp4.dhcp4/21] DHCP4_PARSER_CREATED created parser for configuration element hosts-database
2017-09-27 12:18:32.056 DEBUG [kea-dhcp4.dhcp4/21] DHCP4_PARSER_CREATED created parser for configuration element interfaces-config
2017-09-27 12:18:32.056 INFO [kea-dhcp4.dhcpsrv/21] DHCPSRV_CFGMGR_ADD_IFACE listening on interface eth0
2017-09-27 12:18:32.056 INFO [kea-dhcp4.dhcpsrv/21] DHCPSRV_CFGMGR_SOCKET_TYPE_DEFAULT "dhcp-socket-type" not specified , using default socket type raw
2017-09-27 12:18:32.056 DEBUG [kea-dhcp4.dhcp4/21] DHCP4_PARSER_CREATED created parser for configuration element lease-database
2017-09-27 12:18:32.056 DEBUG [kea-dhcp4.dhcp4/21] DHCP4_PARSER_CREATED created parser for configuration element option-data
2017-09-27 12:18:32.056 DEBUG [kea-dhcp4.dhcp4/21] DHCP4_PARSER_CREATED created parser for configuration element subnet4
2017-09-27 12:18:32.056 DEBUG [kea-dhcp4.dhcp4/21] DHCP4_PARSER_CREATED created parser for configuration element valid-lifetime
2017-09-27 12:18:32.056 INFO [kea-dhcp4.dhcp4/21] DHCP4_CONFIG_NEW_SUBNET a new subnet has been added to configuration: 10.93.208.0/22 with params: valid-lifetime=300
2017-09-27 12:18:32.056 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_CFGMGR_ADD_SUBNET4 adding subnet 10.93.208.0/22
2017-09-27 12:18:32.056 INFO [kea-dhcp4.dhcp4/21] DHCP4_CONFIG_COMPLETE DHCPv4 server has completed configuration: added IPv4 subnets: 1; DDNS: enabled
2017-09-27 12:18:32.057 INFO [kea-dhcp4.dhcpsrv/21] DHCPSRV_MYSQL_DB opening MySQL lease database: host=0.0.0.0 name=keadb password=***** type=mysql universe=4 user=kea
2017-09-27 12:18:32.074 INFO [kea-dhcp4.dhcpsrv/21] DHCPSRV_MYSQL_HOST_DB opening MySQL hosts database: host=0.0.0.0 name=keadb password=***** type=mysql universe=4 user=kea
2017-09-27 12:18:32.085 INFO [kea-dhcp4.dhcpsrv/21] DHCPSRV_DHCP_DDNS_SENDER_STARTED NameChangeRequest sender has been started: enable_updates: yes, server_ip: 127.0.0.1, server_port: 53001, sender_ip: 0.0.0.0, sender_port: 0, max_queue_size: 1024, ncr_protocol: UDP, ncr_format: JSON, always_include_fqdn: no, override_no_update: no, override_client_update: no, replace_client_name: always, generated_prefix: [pre], qualifying_suffix: [stagingdhcp.example.com.]
2017-09-27 12:18:32.093 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_TIMERMGR_REGISTER_TIMER registering timer: reclaim-expired-leases, using interval: 10000 ms
2017-09-27 12:18:32.093 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_TIMERMGR_START_TIMER starting timer: reclaim-expired-leases
2017-09-27 12:18:32.093 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_TIMERMGR_REGISTER_TIMER registering timer: flush-reclaimed-leases, using interval: 25000 ms
2017-09-27 12:18:32.093 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_TIMERMGR_START_TIMER starting timer: flush-reclaimed-leases
2017-09-27 12:18:32.093 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_TIMERMGR_START_THREAD starting thread for timers
2017-09-27 12:18:32.094 INFO [kea-dhcp4.dhcp4/21] DHCP4_STARTED Kea DHCPv4 server version 1.1.0 started
2017-09-27 12:18:32.094 DEBUG [kea-dhcp4.packets/21] DHCP4_BUFFER_WAIT waiting for next DHCPv4 packet with timeout 1000 ms
2017-09-27 12:18:42.093 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_TIMERMGR_RUN_TIMER_OPERATION running operation for timer: reclaim-expired-leases
2017-09-27 12:18:42.093 DEBUG [kea-dhcp4.alloc-engine/21] ALLOC_ENGINE_V4_LEASES_RECLAMATION_START starting reclamation of expired leases (limit = 100 leases or 250 milliseconds)
2017-09-27 12:18:42.093 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_MYSQL_GET_EXPIRED4 obtaining maximum 101 of expired IPv4 leases
2017-09-27 12:18:42.095 DEBUG [kea-dhcp4.alloc-engine/21] ALLOC_ENGINE_V4_LEASES_RECLAMATION_COMPLETE reclaimed 0 leases in 1.235 ms
2017-09-27 12:18:42.095 DEBUG [kea-dhcp4.alloc-engine/21] ALLOC_ENGINE_V4_NO_MORE_EXPIRED_LEASES all expired leases have been reclaimed
2017-09-27 12:18:42.095 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_TIMERMGR_START_TIMER starting timer: reclaim-expired-leases
...
2017-09-27 12:19:02.098 DEBUG [kea-dhcp4.packets/21] DHCP4_BUFFER_WAIT_INTERRUPTED interrupted wait for the next packet due to timeout, signal or external socket callback (timeout value is 1000)
2017-09-27 12:19:02.098 DEBUG [kea-dhcp4.packets/21] DHCP4_BUFFER_WAIT waiting for next DHCPv4 packet with timeout 1000 ms
2017-09-27 12:19:12.098 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_TIMERMGR_RUN_TIMER_OPERATION running operation for timer: reclaim-expired-leases
2017-09-27 12:19:12.098 DEBUG [kea-dhcp4.alloc-engine/21] ALLOC_ENGINE_V4_LEASES_RECLAMATION_START starting reclamation of expired leases (limit = 100 leases or 250 milliseconds)
2017-09-27 12:19:12.098 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_MYSQL_GET_EXPIRED4 obtaining maximum 101 of expired IPv4 leases
2017-09-27 12:19:12.105 DEBUG [kea-dhcp4.alloc-engine/21] ALLOC_ENGINE_V4_LEASES_RECLAMATION_COMPLETE reclaimed 0 leases in 7.229 ms
2017-09-27 12:19:12.105 DEBUG [kea-dhcp4.alloc-engine/21] ALLOC_ENGINE_V4_NO_MORE_EXPIRED_LEASES all expired leases have been reclaimed
2017-09-27 12:19:12.105 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_TIMERMGR_START_TIMER starting timer: reclaim-expired-leases
2017-09-27 12:19:12.105 DEBUG [kea-dhcp4.packets/21] DHCP4_BUFFER_WAIT_INTERRUPTED interrupted wait for the next packet due to timeout, signal or external socket callback (timeout value is 1000)
2017-09-27 12:19:12.105 DEBUG [kea-dhcp4.packets/21] DHCP4_BUFFER_WAIT waiting for next DHCPv4 packet with timeout 1000 ms
2017-09-27 12:19:21.825 DEBUG [kea-dhcp4.packets/21] DHCP4_BUFFER_RECEIVED received buffer from 0.0.0.0:68 to 255.255.255.255:67 over interface eth0
2017-09-27 12:19:21.825 DEBUG [kea-dhcp4.options/21] DHCP4_BUFFER_UNPACK parsing buffer received from 0.0.0.0 to 255.255.255.255 over interface eth0
2017-09-27 12:19:21.825 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_CFGMGR_SUBNET4_ADDR selected subnet 10.93.208.0/22 for packet received by matching address 10.93.211.251
2017-09-27 12:19:21.825 DEBUG [kea-dhcp4.packets/21] DHCP4_SUBNET_SELECTED [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: the subnet with ID 351 was selected for client assignments
2017-09-27 12:19:21.825 DEBUG [kea-dhcp4.packets/21] DHCP4_SUBNET_DATA [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: the selected subnet details: 10.93.208.0/22
2017-09-27 12:19:21.825 DEBUG [kea-dhcp4.packets/21] DHCP4_PACKET_RECEIVED [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: DHCPDISCOVER (type 1) received from 0.0.0.0 to 255.255.255.255 on interface eth0
2017-09-27 12:19:21.825 DEBUG [kea-dhcp4.packets/21] DHCP4_QUERY_DATA [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536, packet details: local_address=255.255.255.255:67, remote_adress=0.0.0.0:68, msg_type=DHCPDISCOVER (1), transid=0x9bf06536,
options:
type=053, len=001: 1 (uint8)
type=055, len=018: 1(uint8) 28(uint8) 2(uint8) 121(uint8) 15(uint8) 6(uint8) 12(uint8) 40(uint8) 41(uint8) 42(uint8) 26(uint8) 119(uint8) 3(uint8) 121(uint8) 249(uint8) 33(uint8) 252(uint8) 42(uint8)
2017-09-27 12:19:21.825 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_CFGMGR_SUBNET4_ADDR selected subnet 10.93.208.0/22 for packet received by matching address 10.93.211.251
2017-09-27 12:19:21.825 DEBUG [kea-dhcp4.packets/21] DHCP4_SUBNET_SELECTED [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: the subnet with ID 351 was selected for client assignments
2017-09-27 12:19:21.825 DEBUG [kea-dhcp4.packets/21] DHCP4_SUBNET_DATA [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: the selected subnet details: 10.93.208.0/22
2017-09-27 12:19:21.825 DEBUG [kea-dhcp4.hosts/21] HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for subnet id 351, identified by hwaddr=005056A827E2
2017-09-27 12:19:21.825 DEBUG [kea-dhcp4.hosts/21] HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier: hwaddr=005056A827E2
2017-09-27 12:19:21.825 DEBUG [kea-dhcp4.hosts/21] HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier hwaddr=005056A827E2, found 0 host(s)
2017-09-27 12:19:21.825 DEBUG [kea-dhcp4.hosts/21] HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 351 and identifier hwaddr=005056A827E2
2017-09-27 12:19:21.831 DEBUG [kea-dhcp4.ddns/21] DHCP4_CLIENT_HOSTNAME_PROCESS [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: processing client's Hostname option
2017-09-27 12:19:21.831 DEBUG [kea-dhcp4.ddns/21] DHCP4_GENERATE_FQDN [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: client did not send a FQDN or hostname; FQDN will be be generated for the client
2017-09-27 12:19:21.831 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_MYSQL_GET_SUBID_HWADDR obtaining IPv4 lease for subnet ID 351 and hardware address hwtype=1 00:50:56:a8:27:e2
2017-09-27 12:19:21.833 DEBUG [kea-dhcp4.alloc-engine/21] ALLOC_ENGINE_V4_OFFER_NEW_LEASE allocation engine will try to offer new lease to the client [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536
2017-09-27 12:19:21.833 DEBUG [kea-dhcp4.hosts/21] HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS4 get one host with reservation for subnet id 351 and IPv4 address 10.93.208.1
2017-09-27 12:19:21.833 DEBUG [kea-dhcp4.hosts/21] HOSTS_CFG_GET_ALL_ADDRESS4 get all hosts with reservations for IPv4 address 10.93.208.1
2017-09-27 12:19:21.833 DEBUG [kea-dhcp4.hosts/21] HOSTS_CFG_GET_ALL_ADDRESS4_COUNT using address 10.93.208.1, found 0 host(s)
2017-09-27 12:19:21.833 DEBUG [kea-dhcp4.hosts/21] HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS4_NULL host not found using subnet id 351 and address 10.93.208.1
2017-09-27 12:19:21.833 DEBUG [kea-dhcp4.hosts/21] HOSTS_MGR_ALTERNATE_GET4_SUBNET_ID_ADDRESS4 trying alternate source for host using subnet id 351 and address 10.93.208.1
2017-09-27 12:19:21.835 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_MYSQL_GET_ADDR4 obtaining IPv4 lease for address 10.93.208.1
2017-09-27 12:19:21.836 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_MYSQL_GET_ADDR4 obtaining IPv4 lease for address 10.93.208.1
2017-09-27 12:19:21.837 INFO [kea-dhcp4.leases/21] DHCP4_LEASE_ADVERT [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: lease 10.93.208.1 will be advertised
2017-09-27 12:19:21.837 DEBUG [kea-dhcp4.ddns/21] DHCP4_RESPONSE_HOSTNAME_GENERATE [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: server has generated hostname pre-10-93-208-1.stagingdhcp.example.com for the client
2017-09-27 12:19:21.837 DEBUG [kea-dhcp4.options/21] DHCP4_PACKET_PACK [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: preparing on-wire format of the packet to be sent
2017-09-27 12:19:21.837 DEBUG [kea-dhcp4.packets/21] DHCP4_PACKET_SEND [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: trying to send packet DHCPOFFER (type 2) from 10.93.211.251:67 to 10.93.208.1:68 on interface eth0
2017-09-27 12:19:21.837 DEBUG [kea-dhcp4.packets/21] DHCP4_RESPONSE_DATA [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: responding with packet DHCPOFFER (type 2), packet details: local_address=10.93.211.251:67, remote_adress=10.93.208.1:68, msg_type=DHCPOFFER (2), transid=0x9bf06536,
options:
type=001, len=004: 4294966272 (uint32)
type=003, len=004: 10.93.211.254
type=006, len=008: 10.93.211.10 10.93.211.11
type=012, len=037: "pre-10-93-208-1.stagingdhcp.example.com" (string)
type=015, len=021: "stagingdhcp.example.com" (string)
type=042, len=016: 10.254.254.254 10.254.191.254 10.254.60.250 10.254.191.250
type=051, len=004: 300 (uint32)
type=053, len=001: 2 (uint8)
type=054, len=004: 10.93.211.251
2017-09-27 12:19:21.837 DEBUG [kea-dhcp4.packets/21] DHCP4_BUFFER_WAIT waiting for next DHCPv4 packet with timeout 1000 ms
2017-09-27 12:19:21.837 DEBUG [kea-dhcp4.packets/21] DHCP4_BUFFER_RECEIVED received buffer from 0.0.0.0:68 to 255.255.255.255:67 over interface eth0
2017-09-27 12:19:21.837 DEBUG [kea-dhcp4.options/21] DHCP4_BUFFER_UNPACK parsing buffer received from 0.0.0.0 to 255.255.255.255 over interface eth0
2017-09-27 12:19:21.837 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_CFGMGR_SUBNET4_ADDR selected subnet 10.93.208.0/22 for packet received by matching address 10.93.211.251
2017-09-27 12:19:21.837 DEBUG [kea-dhcp4.packets/21] DHCP4_SUBNET_SELECTED [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: the subnet with ID 351 was selected for client assignments
2017-09-27 12:19:21.837 DEBUG [kea-dhcp4.packets/21] DHCP4_SUBNET_DATA [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: the selected subnet details: 10.93.208.0/22
2017-09-27 12:19:21.837 DEBUG [kea-dhcp4.packets/21] DHCP4_PACKET_RECEIVED [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: DHCPREQUEST (type 3) received from 0.0.0.0 to 255.255.255.255 on interface eth0
2017-09-27 12:19:21.837 DEBUG [kea-dhcp4.packets/21] DHCP4_QUERY_DATA [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536, packet details: local_address=255.255.255.255:67, remote_adress=0.0.0.0:68, msg_type=DHCPREQUEST (3), transid=0x9bf06536,
options:
type=050, len=004: 10.93.208.1 (ipv4-address)
type=053, len=001: 3 (uint8)
type=054, len=004: 10.93.211.251 (ipv4-address)
type=055, len=018: 1(uint8) 28(uint8) 2(uint8) 121(uint8) 15(uint8) 6(uint8) 12(uint8) 40(uint8) 41(uint8) 42(uint8) 26(uint8) 119(uint8) 3(uint8) 121(uint8) 249(uint8) 33(uint8) 252(uint8) 42(uint8)
2017-09-27 12:19:21.837 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_CFGMGR_SUBNET4_ADDR selected subnet 10.93.208.0/22 for packet received by matching address 10.93.211.251
2017-09-27 12:19:21.837 DEBUG [kea-dhcp4.packets/21] DHCP4_SUBNET_SELECTED [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: the subnet with ID 351 was selected for client assignments
2017-09-27 12:19:21.837 DEBUG [kea-dhcp4.packets/21] DHCP4_SUBNET_DATA [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: the selected subnet details: 10.93.208.0/22
2017-09-27 12:19:21.837 DEBUG [kea-dhcp4.hosts/21] HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for subnet id 351, identified by hwaddr=005056A827E2
2017-09-27 12:19:21.838 DEBUG [kea-dhcp4.hosts/21] HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier: hwaddr=005056A827E2
2017-09-27 12:19:21.838 DEBUG [kea-dhcp4.hosts/21] HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier hwaddr=005056A827E2, found 0 host(s)
2017-09-27 12:19:21.838 DEBUG [kea-dhcp4.hosts/21] HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 351 and identifier hwaddr=005056A827E2
2017-09-27 12:19:21.848 DEBUG [kea-dhcp4.ddns/21] DHCP4_CLIENT_HOSTNAME_PROCESS [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: processing client's Hostname option
2017-09-27 12:19:21.848 DEBUG [kea-dhcp4.ddns/21] DHCP4_GENERATE_FQDN [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: client did not send a FQDN or hostname; FQDN will be be generated for the client
2017-09-27 12:19:21.848 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_MYSQL_GET_SUBID_HWADDR obtaining IPv4 lease for subnet ID 351 and hardware address hwtype=1 00:50:56:a8:27:e2
2017-09-27 12:19:21.849 DEBUG [kea-dhcp4.hosts/21] HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS4 get one host with reservation for subnet id 351 and IPv4 address 10.93.208.1
2017-09-27 12:19:21.849 DEBUG [kea-dhcp4.hosts/21] HOSTS_CFG_GET_ALL_ADDRESS4 get all hosts with reservations for IPv4 address 10.93.208.1
2017-09-27 12:19:21.849 DEBUG [kea-dhcp4.hosts/21] HOSTS_CFG_GET_ALL_ADDRESS4_COUNT using address 10.93.208.1, found 0 host(s)
2017-09-27 12:19:21.849 DEBUG [kea-dhcp4.hosts/21] HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS4_NULL host not found using subnet id 351 and address 10.93.208.1
2017-09-27 12:19:21.849 DEBUG [kea-dhcp4.hosts/21] HOSTS_MGR_ALTERNATE_GET4_SUBNET_ID_ADDRESS4 trying alternate source for host using subnet id 351 and address 10.93.208.1
2017-09-27 12:19:21.851 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_MYSQL_GET_ADDR4 obtaining IPv4 lease for address 10.93.208.1
2017-09-27 12:19:21.852 DEBUG [kea-dhcp4.alloc-engine/21] ALLOC_ENGINE_V4_REQUEST_ALLOC_REQUESTED [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: trying to allocate requested address 10.93.208.1
2017-09-27 12:19:21.852 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_MYSQL_GET_ADDR4 obtaining IPv4 lease for address 10.93.208.1
2017-09-27 12:19:21.853 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_MYSQL_ADD_ADDR4 adding IPv4 lease with address 10.93.208.1
2017-09-27 12:19:21.866 INFO [kea-dhcp4.leases/21] DHCP4_LEASE_ALLOC [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: lease 10.93.208.1 has been allocated
2017-09-27 12:19:21.866 DEBUG [kea-dhcp4.ddns/21] DHCP4_RESPONSE_HOSTNAME_GENERATE [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: server has generated hostname pre-10-93-208-1.stagingdhcp.example.com for the client
2017-09-27 12:19:21.866 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_MYSQL_UPDATE_ADDR4 updating IPv4 lease for address 10.93.208.1
2017-09-27 12:19:21.878 DEBUG [kea-dhcp4.ddns/21] DHCP4_NCR_CREATE [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: DDNS updates enabled, therefore sending name change requests
2017-09-27 12:19:21.879 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_QUEUE_NCR [hwtype=1 00:50:56:a8:27:e2], cid=[no info]: name change request to add DNS entry queued: Type: 0 (CHG_ADD)
Forward Change: yes
Reverse Change: yes
FQDN: [pre-10-93-208-1.stagingdhcp.example.com.]
IP Address: [10.93.208.1]
DHCID: [000001E61EC121FD8E9CA6B831936C799BA6B47E7D792702932E307CA8314DAD666E62]
Lease Expires On: 20170927122421
Lease Length: 300
2017-09-27 12:19:21.879 DEBUG [kea-dhcp4.options/21] DHCP4_PACKET_PACK [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: preparing on-wire format of the packet to be sent
2017-09-27 12:19:21.879 DEBUG [kea-dhcp4.packets/21] DHCP4_PACKET_SEND [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: trying to send packet DHCPACK (type 5) from 10.93.211.251:67 to 10.93.208.1:68 on interface eth0
2017-09-27 12:19:21.879 DEBUG [kea-dhcp4.packets/21] DHCP4_RESPONSE_DATA [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: responding with packet DHCPACK (type 5), packet details: local_address=10.93.211.251:67, remote_adress=10.93.208.1:68, msg_type=DHCPACK (5), transid=0x9bf06536,
options:
type=001, len=004: 4294966272 (uint32)
type=003, len=004: 10.93.211.254
type=006, len=008: 10.93.211.10 10.93.211.11
type=012, len=037: "pre-10-93-208-1.stagingdhcp.example.com" (string)
type=015, len=021: "stagingdhcp.example.com" (string)
type=042, len=016: 10.254.254.254 10.254.191.254 10.254.60.250 10.254.191.250
type=051, len=004: 300 (uint32)
type=053, len=001: 5 (uint8)
type=054, len=004: 10.93.211.251
2017-09-27 12:19:21.879 DEBUG [kea-dhcp4.packets/21] DHCP4_BUFFER_WAIT waiting for next DHCPv4 packet with timeout 1000 ms
2017-09-27 12:19:21.879 DEBUG [kea-dhcp-ddns.dhcp-to-d2/19] DHCP_DDNS_QUEUE_MGR_QUEUE_RECEIVE Request ID 000001E61EC121FD8E9CA6B831936C799BA6B47E7D792702932E307CA8314DAD666E62: received and queued a request.
2017-09-27 12:19:21.879 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_DHCP_DDNS_NCR_SENT NameChangeRequest sent to kea-dhcp-ddns: Type: 0 (CHG_ADD)
Forward Change: yes
Reverse Change: yes
FQDN: [pre-10-93-208-1.stagingdhcp.example.com.]
IP Address: [10.93.208.1]
DHCID: [000001E61EC121FD8E9CA6B831936C799BA6B47E7D792702932E307CA8314DAD666E62]
Lease Expires On: 20170927122421
Lease Length: 300
2017-09-27 12:19:21.879 DEBUG [kea-dhcp4.packets/21] DHCP4_BUFFER_WAIT_INTERRUPTED interrupted wait for the next packet due to timeout, signal or external socket callback (timeout value is 1000)
2017-09-27 12:19:21.879 DEBUG [kea-dhcp-ddns.d2-to-dns/19] DHCP_DDNS_STARTING_TRANSACTION Request ID 000001E61EC121FD8E9CA6B831936C799BA6B47E7D792702932E307CA8314DAD666E62:
2017-09-27 12:19:21.879 DEBUG [kea-dhcp4.packets/21] DHCP4_BUFFER_WAIT waiting for next DHCPv4 packet with timeout 1000 ms
2017-09-27 12:19:21.879 DEBUG [kea-dhcp-ddns.d2-to-dns/19] DHCP_DDNS_UPDATE_REQUEST_SENT Request ID 000001E61EC121FD8E9CA6B831936C799BA6B47E7D792702932E307CA8314DAD666E62: Foward Add to server: 10.93.211.12 port:53
2017-09-27 12:19:21.909 DEBUG [kea-dhcp-ddns.asiodns/19] ASIODNS_FETCH_COMPLETED upstream fetch to 10.93.211.12(53) has now completed
2017-09-27 12:19:21.909 DEBUG [kea-dhcp-ddns.d2-to-dns/19] DHCP_DDNS_UPDATE_RESPONSE_RECEIVED Request ID 000001E61EC121FD8E9CA6B831936C799BA6B47E7D792702932E307CA8314DAD666E62: to server: 10.93.211.12 port:53 status: SUCCESS, rcode: NOERROR
2017-09-27 12:19:21.909 DEBUG [kea-dhcp-ddns.d2-to-dns/19] DHCP_DDNS_UPDATE_REQUEST_SENT Request ID 000001E61EC121FD8E9CA6B831936C799BA6B47E7D792702932E307CA8314DAD666E62: Reverse Replace to server: 10.93.211.12 port:53
2017-09-27 12:19:21.941 DEBUG [kea-dhcp-ddns.asiodns/19] ASIODNS_FETCH_COMPLETED upstream fetch to 10.93.211.12(53) has now completed
2017-09-27 12:19:21.941 DEBUG [kea-dhcp-ddns.d2-to-dns/19] DHCP_DDNS_UPDATE_RESPONSE_RECEIVED Request ID 000001E61EC121FD8E9CA6B831936C799BA6B47E7D792702932E307CA8314DAD666E62: to server: 10.93.211.12 port:53 status: SUCCESS, rcode: NOERROR
2017-09-27 12:19:21.941 INFO [kea-dhcp-ddns.d2-to-dns/19] DHCP_DDNS_ADD_SUCCEEDED DHCP_DDNS Request ID 000001E61EC121FD8E9CA6B831936C799BA6B47E7D792702932E307CA8314DAD666E62: successfully added the DNS mapping addition for this request: Type: 0 (CHG_ADD)
Forward Change: yes
Reverse Change: yes
FQDN: [pre-10-93-208-1.stagingdhcp.example.com.]
IP Address: [10.93.208.1]
DHCID: [000001E61EC121FD8E9CA6B831936C799BA6B47E7D792702932E307CA8314DAD666E62]
Lease Expires On: 20170927122421
Lease Length: 300
2017-09-27 12:19:22.095 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_TIMERMGR_RUN_TIMER_OPERATION running operation for timer: flush-reclaimed-leases
2017-09-27 12:19:22.095 DEBUG [kea-dhcp4.alloc-engine/21] ALLOC_ENGINE_V4_RECLAIMED_LEASES_DELETE begin deletion of reclaimed leases expired more than 360 seconds ago
2017-09-27 12:19:22.095 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_MYSQL_DELETE_EXPIRED_RECLAIMED4 deleting reclaimed IPv4 leases that expired more than 360 seconds ago
2017-09-27 12:19:22.096 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_MYSQL_DELETED_EXPIRED_RECLAIMED deleted 0 reclaimed leases from the database
2017-09-27 12:19:22.097 DEBUG [kea-dhcp4.alloc-engine/21] ALLOC_ENGINE_V4_RECLAIMED_LEASES_DELETE_COMPLETE successfully deleted 0 expired-reclaimed leases
2017-09-27 12:19:22.097 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_TIMERMGR_START_TIMER starting timer: flush-reclaimed-leases
2017-09-27 12:19:22.097 DEBUG [kea-dhcp4.packets/21] DHCP4_BUFFER_WAIT_INTERRUPTED interrupted wait for the next packet due to timeout, signal or external socket callback (timeout value is 1000)
2017-09-27 12:19:22.097 DEBUG [kea-dhcp4.packets/21] DHCP4_BUFFER_WAIT waiting for next DHCPv4 packet with timeout 1000 ms
2017-09-27 12:19:22.106 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_TIMERMGR_RUN_TIMER_OPERATION running operation for timer: reclaim-expired-leases
2017-09-27 12:19:22.106 DEBUG [kea-dhcp4.alloc-engine/21] ALLOC_ENGINE_V4_LEASES_RECLAMATION_START starting reclamation of expired leases (limit = 100 leases or 250 milliseconds)
2017-09-27 12:19:22.106 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_MYSQL_GET_EXPIRED4 obtaining maximum 101 of expired IPv4 leases
...
2017-09-27 12:21:27.104 DEBUG [kea-dhcp4.packets/21] DHCP4_BUFFER_WAIT_INTERRUPTED interrupted wait for the next packet due to timeout, signal or external socket callback (timeout value is 1000)
2017-09-27 12:21:27.104 DEBUG [kea-dhcp4.packets/21] DHCP4_BUFFER_WAIT waiting for next DHCPv4 packet with timeout 1000 ms
2017-09-27 12:21:31.845 DEBUG [kea-dhcp4.packets/21] DHCP4_BUFFER_RECEIVED received buffer from 10.93.208.1:68 to 10.93.211.251:67 over interface eth0
2017-09-27 12:21:31.845 DEBUG [kea-dhcp4.options/21] DHCP4_BUFFER_UNPACK parsing buffer received from 10.93.208.1 to 10.93.211.251 over interface eth0
2017-09-27 12:21:31.846 DEBUG [kea-dhcp4.packets/21] DHCP4_PACKET_RECEIVED [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: DHCPREQUEST (type 3) received from 10.93.208.1 to 10.93.211.251 on interface eth0
2017-09-27 12:21:31.846 DEBUG [kea-dhcp4.packets/21] DHCP4_QUERY_DATA [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536, packet details: local_address=10.93.211.251:67, remote_adress=10.93.208.1:68, msg_type=DHCPREQUEST (3), transid=0x9bf06536,
options:
type=053, len=001: 3 (uint8)
type=055, len=018: 1(uint8) 28(uint8) 2(uint8) 121(uint8) 15(uint8) 6(uint8) 12(uint8) 40(uint8) 41(uint8) 42(uint8) 26(uint8) 119(uint8) 3(uint8) 121(uint8) 249(uint8) 33(uint8) 252(uint8) 42(uint8)
2017-09-27 12:21:31.846 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_CFGMGR_SUBNET4_ADDR selected subnet 10.93.208.0/22 for packet received by matching address 10.93.208.1
2017-09-27 12:21:31.846 DEBUG [kea-dhcp4.packets/21] DHCP4_SUBNET_SELECTED [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: the subnet with ID 351 was selected for client assignments
2017-09-27 12:21:31.846 DEBUG [kea-dhcp4.packets/21] DHCP4_SUBNET_DATA [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: the selected subnet details: 10.93.208.0/22
2017-09-27 12:21:31.846 DEBUG [kea-dhcp4.hosts/21] HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER get one host with IPv4 reservation for subnet id 351, identified by hwaddr=005056A827E2
2017-09-27 12:21:31.846 DEBUG [kea-dhcp4.hosts/21] HOSTS_CFG_GET_ALL_IDENTIFIER get all hosts with reservations using identifier: hwaddr=005056A827E2
2017-09-27 12:21:31.846 DEBUG [kea-dhcp4.hosts/21] HOSTS_CFG_GET_ALL_IDENTIFIER_COUNT using identifier hwaddr=005056A827E2, found 0 host(s)
2017-09-27 12:21:31.846 DEBUG [kea-dhcp4.hosts/21] HOSTS_CFG_GET_ONE_SUBNET_ID_IDENTIFIER_NULL host not found using subnet id 351 and identifier hwaddr=005056A827E2
2017-09-27 12:21:31.847 DEBUG [kea-dhcp4.ddns/21] DHCP4_CLIENT_HOSTNAME_PROCESS [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: processing client's Hostname option
2017-09-27 12:21:31.847 DEBUG [kea-dhcp4.ddns/21] DHCP4_GENERATE_FQDN [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: client did not send a FQDN or hostname; FQDN will be be generated for the client
2017-09-27 12:21:31.847 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_MYSQL_GET_SUBID_HWADDR obtaining IPv4 lease for subnet ID 351 and hardware address hwtype=1 00:50:56:a8:27:e2
2017-09-27 12:21:31.848 DEBUG [kea-dhcp4.hosts/21] HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS4 get one host with reservation for subnet id 351 and IPv4 address 10.93.208.1
2017-09-27 12:21:31.848 DEBUG [kea-dhcp4.hosts/21] HOSTS_CFG_GET_ALL_ADDRESS4 get all hosts with reservations for IPv4 address 10.93.208.1
2017-09-27 12:21:31.848 DEBUG [kea-dhcp4.hosts/21] HOSTS_CFG_GET_ALL_ADDRESS4_COUNT using address 10.93.208.1, found 0 host(s)
2017-09-27 12:21:31.848 DEBUG [kea-dhcp4.hosts/21] HOSTS_CFG_GET_ONE_SUBNET_ID_ADDRESS4_NULL host not found using subnet id 351 and address 10.93.208.1
2017-09-27 12:21:31.848 DEBUG [kea-dhcp4.hosts/21] HOSTS_MGR_ALTERNATE_GET4_SUBNET_ID_ADDRESS4 trying alternate source for host using subnet id 351 and address 10.93.208.1
2017-09-27 12:21:31.849 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_MYSQL_GET_ADDR4 obtaining IPv4 lease for address 10.93.208.1
2017-09-27 12:21:31.850 DEBUG [kea-dhcp4.alloc-engine/21] ALLOC_ENGINE_V4_REQUEST_EXTEND_LEASE [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: extending lifetime of the lease for address 10.93.208.1
2017-09-27 12:21:31.850 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_QUEUE_NCR [hwtype=1 00:50:56:a8:27:e2], cid=[no info]: name change request to remove DNS entry queued: Type: 1 (CHG_REMOVE)
Forward Change: yes
Reverse Change: yes
FQDN: [pre-10-93-208-1.stagingdhcp.example.com.]
IP Address: [10.93.208.1]
DHCID: [000001E61EC121FD8E9CA6B831936C799BA6B47E7D792702932E307CA8314DAD666E62]
Lease Expires On: 20170927122421
Lease Length: 300
2017-09-27 12:21:31.850 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_MYSQL_UPDATE_ADDR4 updating IPv4 lease for address 10.93.208.1
2017-09-27 12:21:31.850 DEBUG [kea-dhcp-ddns.dhcp-to-d2/19] DHCP_DDNS_QUEUE_MGR_QUEUE_RECEIVE Request ID 000001E61EC121FD8E9CA6B831936C799BA6B47E7D792702932E307CA8314DAD666E62: received and queued a request.
2017-09-27 12:21:31.850 DEBUG [kea-dhcp-ddns.d2-to-dns/19] DHCP_DDNS_STARTING_TRANSACTION Request ID 000001E61EC121FD8E9CA6B831936C799BA6B47E7D792702932E307CA8314DAD666E62:
2017-09-27 12:21:31.850 DEBUG [kea-dhcp-ddns.d2-to-dns/19] DHCP_DDNS_UPDATE_REQUEST_SENT Request ID 000001E61EC121FD8E9CA6B831936C799BA6B47E7D792702932E307CA8314DAD666E62: Forward A/AAAA Remove to server: 10.93.211.12 port:53
2017-09-27 12:21:31.860 INFO [kea-dhcp4.leases/21] DHCP4_LEASE_ALLOC [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: lease 10.93.208.1 has been allocated
2017-09-27 12:21:31.860 DEBUG [kea-dhcp4.ddns/21] DHCP4_RESPONSE_HOSTNAME_GENERATE [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: server has generated hostname pre-10-93-208-1.stagingdhcp.example.com for the client
2017-09-27 12:21:31.860 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_MYSQL_UPDATE_ADDR4 updating IPv4 lease for address 10.93.208.1
2017-09-27 12:21:31.870 DEBUG [kea-dhcp4.ddns/21] DHCP4_NCR_CREATE [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: DDNS updates enabled, therefore sending name change requests
2017-09-27 12:21:31.870 DEBUG [kea-dhcp4.options/21] DHCP4_PACKET_PACK [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: preparing on-wire format of the packet to be sent
2017-09-27 12:21:31.870 DEBUG [kea-dhcp4.packets/21] DHCP4_PACKET_SEND [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: trying to send packet DHCPACK (type 5) from 10.93.211.251:67 to 10.93.208.1:68 on interface eth0
2017-09-27 12:21:31.870 DEBUG [kea-dhcp4.packets/21] DHCP4_RESPONSE_DATA [hwtype=1 00:50:56:a8:27:e2], cid=[no info], tid=0x9bf06536: responding with packet DHCPACK (type 5), packet details: local_address=10.93.211.251:67, remote_adress=10.93.208.1:68, msg_type=DHCPACK (5), transid=0x9bf06536,
options:
type=001, len=004: 4294966272 (uint32)
type=003, len=004: 10.93.211.254
type=006, len=008: 10.93.211.10 10.93.211.11
type=012, len=037: "pre-10-93-208-1.stagingdhcp.example.com" (string)
type=015, len=021: "stagingdhcp.example.com" (string)
type=042, len=016: 10.254.254.254 10.254.191.254 10.254.60.250 10.254.191.250
type=051, len=004: 300 (uint32)
type=053, len=001: 5 (uint8)
type=054, len=004: 10.93.211.251
2017-09-27 12:21:31.870 DEBUG [kea-dhcp4.packets/21] DHCP4_BUFFER_WAIT waiting for next DHCPv4 packet with timeout 1000 ms
2017-09-27 12:21:31.870 DEBUG [kea-dhcp4.dhcpsrv/21] DHCPSRV_DHCP_DDNS_NCR_SENT NameChangeRequest sent to kea-dhcp-ddns: Type: 1 (CHG_REMOVE)
Forward Change: yes
Reverse Change: yes
FQDN: [pre-10-93-208-1.stagingdhcp.example.com.]
IP Address: [10.93.208.1]
DHCID: [000001E61EC121FD8E9CA6B831936C799BA6B47E7D792702932E307CA8314DAD666E62]
Lease Expires On: 20170927122421
Lease Length: 300
2017-09-27 12:21:31.870 DEBUG [kea-dhcp4.packets/21] DHCP4_BUFFER_WAIT_INTERRUPTED interrupted wait for the next packet due to timeout, signal or external socket callback (timeout value is 1000)
2017-09-27 12:21:31.870 DEBUG [kea-dhcp4.packets/21] DHCP4_BUFFER_WAIT waiting for next DHCPv4 packet with timeout 1000 ms
2017-09-27 12:21:31.883 DEBUG [kea-dhcp-ddns.asiodns/19] ASIODNS_FETCH_COMPLETED upstream fetch to 10.93.211.12(53) has now completed
2017-09-27 12:21:31.884 DEBUG [kea-dhcp-ddns.d2-to-dns/19] DHCP_DDNS_UPDATE_RESPONSE_RECEIVED Request ID 000001E61EC121FD8E9CA6B831936C799BA6B47E7D792702932E307CA8314DAD666E62: to server: 10.93.211.12 port:53 status: SUCCESS, rcode: NOERROR
2017-09-27 12:21:31.884 DEBUG [kea-dhcp-ddns.d2-to-dns/19] DHCP_DDNS_UPDATE_REQUEST_SENT Request ID 000001E61EC121FD8E9CA6B831936C799BA6B47E7D792702932E307CA8314DAD666E62: Forward RR Remove to server: 10.93.211.12 port:53
2017-09-27 12:21:31.912 DEBUG [kea-dhcp-ddns.asiodns/19] ASIODNS_FETCH_COMPLETED upstream fetch to 10.93.211.12(53) has now completed
2017-09-27 12:21:31.912 DEBUG [kea-dhcp-ddns.d2-to-dns/19] DHCP_DDNS_UPDATE_RESPONSE_RECEIVED Request ID 000001E61EC121FD8E9CA6B831936C799BA6B47E7D792702932E307CA8314DAD666E62: to server: 10.93.211.12 port:53 status: SUCCESS, rcode: NOERROR
2017-09-27 12:21:31.912 DEBUG [kea-dhcp-ddns.d2-to-dns/19] DHCP_DDNS_UPDATE_REQUEST_SENT Request ID 000001E61EC121FD8E9CA6B831936C799BA6B47E7D792702932E307CA8314DAD666E62: Reverse Remove to server: 10.93.211.12 port:53
2017-09-27 12:21:31.940 DEBUG [kea-dhcp-ddns.asiodns/19] ASIODNS_FETCH_COMPLETED upstream fetch to 10.93.211.12(53) has now completed
2017-09-27 12:21:31.940 DEBUG [kea-dhcp-ddns.d2-to-dns/19] DHCP_DDNS_UPDATE_RESPONSE_RECEIVED Request ID 000001E61EC121FD8E9CA6B831936C799BA6B47E7D792702932E307CA8314DAD666E62: to server: 10.93.211.12 port:53 status: SUCCESS, rcode: NOERROR
2017-09-27 12:21:31.940 INFO [kea-dhcp-ddns.d2-to-dns/19] DHCP_DDNS_REMOVE_SUCCEEDED DHCP_DDNS Request ID 000001E61EC121FD8E9CA6B831936C799BA6B47E7D792702932E307CA8314DAD666E62: successfully removed the DNS mapping addition for this request: Type: 1 (CHG_REMOVE)
Forward Change: yes
Reverse Change: yes
FQDN: [pre-10-93-208-1.stagingdhcp.example.com.]
IP Address: [10.93.208.1]
DHCID: [000001E61EC121FD8E9CA6B831936C799BA6B47E7D792702932E307CA8314DAD666E62]
Lease Expires On: 20170927122421
Lease Length: 300
Kind regards,
Marcos David
On 27/09/2017, 12:31, "Kea-users on behalf of Thomas Markwalder" <kea-users-bounces at lists.isc.org on behalf of tmark at isc.org> wrote:
Hello David:
We're looking into this for you. Could you supply logging that shows
the original request as well?
Regards,
Thomas Markwalder
ISC Software Engineering
_______________________________________________
Kea-users mailing list
Kea-users at lists.isc.org
https://lists.isc.org/mailman/listinfo/kea-users
Information in this email including any attachments may be privileged, confidential and is intended exclusively for the addressee. The views expressed may not be official policy, but the personal views of the originator. If you have received it in error, please notify the sender by return e-mail and delete it from your system. You should not reproduce, distribute, store, retransmit, use or disclose its contents to anyone. Please note we reserve the right to monitor all e-mail communication through our internal and external networks. SKY and the SKY marks are trademarks of Sky plc and Sky International AG and are used under licence.
Sky UK Limited (Registration No. 2906991), Sky-In-Home Service Limited (Registration No. 2067075) and Sky Subscribers Services Limited (Registration No. 2340150) are direct or indirect subsidiaries of Sky plc (Registration No. 2247735). All of the companies mentioned in this paragraph are incorporated in England and Wales and share the same registered office at Grant Way, Isleworth, Middlesex TW7 5QD.
More information about the Kea-users
mailing list