crash in bulk lease query
I'm experiencing crash while using BLQ, What I could find is that is related to "max-leases-per-fetch" setting.
Scenario:
- assign 20 leases (or start kea with 20 leases already assigned)
- open tcp connection
- send BLQ query type 5
- receive data... and this is problematic step if
"max-leases-per-fetch"is set to less than number of leases in memfile - Kea crashes (in the test it's set to 5). If that value is equal or higher then number of leases entire procedure is successful (all messages: LEASEQUERY-REPLY, multiple LEASEQUERY-DATA, LEASEQUERY-DONE, are generated and send correctly)
- config file kea-dhcp6.conf
- leases file leases.csv
Logs:
wlodek@debian9-64-2:~ $ 2022-12-13 08:53:49.755 INFO [kea-dhcp6.dhcp6/101535.139952525041536] DHCP6_STARTING Kea DHCPv6 server version 2.3.4-git (development) starting
2022-12-13 08:53:49.756 WARN [kea-dhcp6.dhcp6/101535.139952525041536] DHCP6_DEVELOPMENT_VERSION This software is a development branch of Kea. It is not recommended for production use.
2022-12-13 08:53:49.757 DEBUG [kea-dhcp6.dhcp6/101535.139952525041536] DHCP6_CONFIG_RECEIVED received configuration: { "control-socket": { "socket-name": "/home/wlodek/installed/git/var/run/kea/control_socket", "socket-type": "unix" }, "expired-leases-processing": { "flush-reclaimed-timer-wait-time": 50000, "reclaim-timer-wait-time": 50000 }, "hooks-libraries": [ { "library": "/home/wlodek/installed/git/lib/kea/hooks/libdhcp_lease_query.so", "parameters": { "advanced": { "active-query-enabled": false, "bulk-query-enabled": true, "extended-info-tables-enabled": true, "lease-query-ip": "2001:db8:1::1000", "lease-query-tcp-port": 9000, "max-concurrent-queries": 4, "max-leases-per-fetch": 5, "max-requester-connections": 10, "max-requester-idle-time": 3000 }, "requesters": [ "2001:db8:1::2000" ] } }, { "library": "/home/wlodek/installed/git/lib/kea/hooks/libdhcp_lease_cmds.so" } ], "interfaces-config": { "interfaces": [ "enp0s9" ] }, "lease-database": { "type": "memfile" }, "loggers": [ { "debuglevel": 99, "name": "kea-dhcp6", "output_options": [ { "output": "stdout" } ], "severity": "DEBUG" } ], "multi-threading": { "enable-multi-threading": true, "packet-queue-size": 16, "thread-pool-size": 2 }, "option-data": [ ], "preferred-lifetime": 8000000, "rebind-timer": 7000000, "renew-timer": 6000000, "shared-networks": [ ], "store-extended-info": true, "subnet6": [ { "interface": "enp0s9", "pools": [ { "pool": "2001:db8:1::1-2001:db8:1::100" } ], "subnet": "2001:db8:1::/64" } ], "valid-lifetime": 9000000 }
2022-12-13 08:53:49.757 DEBUG [kea-dhcp6.dhcp6/101535.139952525041536] DHCP6_CONFIG_START DHCPv6 server is processing the following configuration: { "control-socket": { "socket-name": "/home/wlodek/installed/git/var/run/kea/control_socket", "socket-type": "unix" }, "expired-leases-processing": { "flush-reclaimed-timer-wait-time": 50000, "reclaim-timer-wait-time": 50000 }, "hooks-libraries": [ { "library": "/home/wlodek/installed/git/lib/kea/hooks/libdhcp_lease_query.so", "parameters": { "advanced": { "active-query-enabled": false, "bulk-query-enabled": true, "extended-info-tables-enabled": true, "lease-query-ip": "2001:db8:1::1000", "lease-query-tcp-port": 9000, "max-concurrent-queries": 4, "max-leases-per-fetch": 5, "max-requester-connections": 10, "max-requester-idle-time": 3000 }, "requesters": [ "2001:db8:1::2000" ] } }, { "library": "/home/wlodek/installed/git/lib/kea/hooks/libdhcp_lease_cmds.so" } ], "interfaces-config": { "interfaces": [ "enp0s9" ] }, "lease-database": { "type": "memfile" }, "loggers": [ { "debuglevel": 99, "name": "kea-dhcp6", "output_options": [ { "output": "stdout" } ], "severity": "DEBUG" } ], "multi-threading": { "enable-multi-threading": true, "packet-queue-size": 16, "thread-pool-size": 2 }, "option-data": [ ], "preferred-lifetime": 8000000, "rebind-timer": 7000000, "renew-timer": 6000000, "shared-networks": [ ], "store-extended-info": true, "subnet6": [ { "interface": "enp0s9", "pools": [ { "pool": "2001:db8:1::1-2001:db8:1::100" } ], "subnet": "2001:db8:1::/64" } ], "valid-lifetime": 9000000 }
2022-12-13 08:53:49.758 DEBUG [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_TIMERMGR_UNREGISTER_ALL_TIMERS unregistering all timers
2022-12-13 08:53:49.758 INFO [kea-dhcp6.hosts/101535.139952525041536] HOSTS_BACKENDS_REGISTERED the following host backend types are available: mysql postgresql
2022-12-13 08:53:49.758 WARN [kea-dhcp6.dhcp6/101535.139952525041536] DHCP6_RESERVATIONS_LOOKUP_FIRST_ENABLED Multi-threading is enabled and host reservations lookup is always performed first.
2022-12-13 08:53:49.759 INFO [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_CFGMGR_ADD_IFACE listening on interface enp0s9
2022-12-13 08:53:49.761 DEBUG [kea-dhcp6.hooks/101535.139952525041536] HOOKS_LIBRARY_VERSION hooks library /home/wlodek/installed/git/lib/kea/hooks/libdhcp_lease_query.so reports its version as 20303
2022-12-13 08:53:49.761 DEBUG [kea-dhcp6.hooks/101535.139952525041536] HOOKS_LIBRARY_MULTI_THREADING_COMPATIBLE hooks library /home/wlodek/installed/git/lib/kea/hooks/libdhcp_lease_query.so reports its multi-threading compatibility as 1
2022-12-13 08:53:49.761 INFO [kea-dhcp6.hooks/101535.139952525041536] HOOKS_LIBRARY_CLOSED hooks library /home/wlodek/installed/git/lib/kea/hooks/libdhcp_lease_query.so successfully closed
2022-12-13 08:53:49.762 DEBUG [kea-dhcp6.hooks/101535.139952525041536] HOOKS_LIBRARY_VERSION hooks library /home/wlodek/installed/git/lib/kea/hooks/libdhcp_lease_cmds.so reports its version as 20303
2022-12-13 08:53:49.762 DEBUG [kea-dhcp6.hooks/101535.139952525041536] HOOKS_LIBRARY_MULTI_THREADING_COMPATIBLE hooks library /home/wlodek/installed/git/lib/kea/hooks/libdhcp_lease_cmds.so reports its multi-threading compatibility as 1
2022-12-13 08:53:49.763 INFO [kea-dhcp6.hooks/101535.139952525041536] HOOKS_LIBRARY_CLOSED hooks library /home/wlodek/installed/git/lib/kea/hooks/libdhcp_lease_cmds.so successfully closed
2022-12-13 08:53:49.763 INFO [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_CFGMGR_NEW_SUBNET6 a new subnet has been added to configuration: 2001:db8:1::/64 with params: t1=6000000, t2=7000000, preferred-lifetime=8000000, valid-lifetime=9000000, rapid-commit is false
2022-12-13 08:53:49.763 DEBUG [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_CFGMGR_ADD_SUBNET6 adding subnet 2001:db8:1::/64
2022-12-13 08:53:49.763 INFO [kea-dhcp6.commands/101535.139952525041536] COMMAND_ACCEPTOR_START Starting to accept connections via unix domain socket bound to /home/wlodek/installed/git/var/run/kea/control_socket
2022-12-13 08:53:49.764 DEBUG [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_CFGMGR_CFG_DHCP_DDNS Setting DHCP-DDNS configuration to: DHCP-DDNS updates disabled
2022-12-13 08:53:49.764 DEBUG [kea-dhcp6.hooks/101535.139952525041536] HOOKS_LIBRARY_LOADING loading hooks library /home/wlodek/installed/git/lib/kea/hooks/libdhcp_lease_query.so
2022-12-13 08:53:49.764 DEBUG [kea-dhcp6.hooks/101535.139952525041536] HOOKS_LIBRARY_VERSION hooks library /home/wlodek/installed/git/lib/kea/hooks/libdhcp_lease_query.so reports its version as 20303
2022-12-13 08:53:49.764 DEBUG [kea-dhcp6.hooks/101535.139952525041536] HOOKS_LIBRARY_MULTI_THREADING_COMPATIBLE hooks library /home/wlodek/installed/git/lib/kea/hooks/libdhcp_lease_query.so reports its multi-threading compatibility as 1
2022-12-13 08:53:49.765 DEBUG [kea-dhcp6.callouts/101535.139952525041536] HOOKS_CALLOUT_REGISTRATION hooks library with index 1 registering callout for hook 'buffer6_receive'
2022-12-13 08:53:49.765 DEBUG [kea-dhcp6.hooks/101535.139952525041536] HOOKS_STD_CALLOUT_REGISTERED hooks library /home/wlodek/installed/git/lib/kea/hooks/libdhcp_lease_query.so registered standard callout for hook buffer6_receive at address 0x7f493a850310
2022-12-13 08:53:49.765 DEBUG [kea-dhcp6.callouts/101535.139952525041536] HOOKS_CALLOUT_REGISTRATION hooks library with index 1 registering callout for hook 'dhcp6_srv_configured'
2022-12-13 08:53:49.765 DEBUG [kea-dhcp6.hooks/101535.139952525041536] HOOKS_STD_CALLOUT_REGISTERED hooks library /home/wlodek/installed/git/lib/kea/hooks/libdhcp_lease_query.so registered standard callout for hook dhcp6_srv_configured at address 0x7f493a851910
2022-12-13 08:53:49.766 INFO [kea-dhcp6.lease-query-hooks/101535.139952525041536] LEASE_QUERY_LOAD_OK Lease Query hooks library loaded successfully.
2022-12-13 08:53:49.766 DEBUG [kea-dhcp6.hooks/101535.139952525041536] HOOKS_LOAD_SUCCESS 'load' function in hook library /home/wlodek/installed/git/lib/kea/hooks/libdhcp_lease_query.so returned success
2022-12-13 08:53:49.766 INFO [kea-dhcp6.hooks/101535.139952525041536] HOOKS_LIBRARY_LOADED hooks library /home/wlodek/installed/git/lib/kea/hooks/libdhcp_lease_query.so successfully loaded
2022-12-13 08:53:49.766 DEBUG [kea-dhcp6.hooks/101535.139952525041536] HOOKS_LIBRARY_LOADING loading hooks library /home/wlodek/installed/git/lib/kea/hooks/libdhcp_lease_cmds.so
2022-12-13 08:53:49.767 DEBUG [kea-dhcp6.hooks/101535.139952525041536] HOOKS_LIBRARY_VERSION hooks library /home/wlodek/installed/git/lib/kea/hooks/libdhcp_lease_cmds.so reports its version as 20303
2022-12-13 08:53:49.767 DEBUG [kea-dhcp6.hooks/101535.139952525041536] HOOKS_LIBRARY_MULTI_THREADING_COMPATIBLE hooks library /home/wlodek/installed/git/lib/kea/hooks/libdhcp_lease_cmds.so reports its multi-threading compatibility as 1
2022-12-13 08:53:49.767 DEBUG [kea-dhcp6.callouts/101535.139952525041536] HOOKS_CALLOUT_REGISTRATION hooks library with index 2 registering callout for hook '$lease4_add'
2022-12-13 08:53:49.768 DEBUG [kea-dhcp6.callouts/101535.139952525041536] HOOKS_CALLOUT_REGISTRATION hooks library with index 2 registering callout for hook '$lease6_add'
2022-12-13 08:53:49.768 DEBUG [kea-dhcp6.callouts/101535.139952525041536] HOOKS_CALLOUT_REGISTRATION hooks library with index 2 registering callout for hook '$lease6_bulk_apply'
2022-12-13 08:53:49.768 DEBUG [kea-dhcp6.callouts/101535.139952525041536] HOOKS_CALLOUT_REGISTRATION hooks library with index 2 registering callout for hook '$lease4_get'
2022-12-13 08:53:49.768 DEBUG [kea-dhcp6.callouts/101535.139952525041536] HOOKS_CALLOUT_REGISTRATION hooks library with index 2 registering callout for hook '$lease6_get'
2022-12-13 08:53:49.768 DEBUG [kea-dhcp6.callouts/101535.139952525041536] HOOKS_CALLOUT_REGISTRATION hooks library with index 2 registering callout for hook '$lease4_get_all'
2022-12-13 08:53:49.769 DEBUG [kea-dhcp6.callouts/101535.139952525041536] HOOKS_CALLOUT_REGISTRATION hooks library with index 2 registering callout for hook '$lease6_get_all'
2022-12-13 08:53:49.769 DEBUG [kea-dhcp6.callouts/101535.139952525041536] HOOKS_CALLOUT_REGISTRATION hooks library with index 2 registering callout for hook '$lease4_get_page'
2022-12-13 08:53:49.769 DEBUG [kea-dhcp6.callouts/101535.139952525041536] HOOKS_CALLOUT_REGISTRATION hooks library with index 2 registering callout for hook '$lease6_get_page'
2022-12-13 08:53:49.769 DEBUG [kea-dhcp6.callouts/101535.139952525041536] HOOKS_CALLOUT_REGISTRATION hooks library with index 2 registering callout for hook '$lease4_get_by_hw_address'
2022-12-13 08:53:49.769 DEBUG [kea-dhcp6.callouts/101535.139952525041536] HOOKS_CALLOUT_REGISTRATION hooks library with index 2 registering callout for hook '$lease4_get_by_client_id'
2022-12-13 08:53:49.770 DEBUG [kea-dhcp6.callouts/101535.139952525041536] HOOKS_CALLOUT_REGISTRATION hooks library with index 2 registering callout for hook '$lease6_get_by_duid'
2022-12-13 08:53:49.770 DEBUG [kea-dhcp6.callouts/101535.139952525041536] HOOKS_CALLOUT_REGISTRATION hooks library with index 2 registering callout for hook '$lease4_get_by_hostname'
2022-12-13 08:53:49.770 DEBUG [kea-dhcp6.callouts/101535.139952525041536] HOOKS_CALLOUT_REGISTRATION hooks library with index 2 registering callout for hook '$lease6_get_by_hostname'
2022-12-13 08:53:49.770 DEBUG [kea-dhcp6.callouts/101535.139952525041536] HOOKS_CALLOUT_REGISTRATION hooks library with index 2 registering callout for hook '$lease4_del'
2022-12-13 08:53:49.770 DEBUG [kea-dhcp6.callouts/101535.139952525041536] HOOKS_CALLOUT_REGISTRATION hooks library with index 2 registering callout for hook '$lease6_del'
2022-12-13 08:53:49.771 DEBUG [kea-dhcp6.callouts/101535.139952525041536] HOOKS_CALLOUT_REGISTRATION hooks library with index 2 registering callout for hook '$lease4_update'
2022-12-13 08:53:49.771 DEBUG [kea-dhcp6.callouts/101535.139952525041536] HOOKS_CALLOUT_REGISTRATION hooks library with index 2 registering callout for hook '$lease6_update'
2022-12-13 08:53:49.771 DEBUG [kea-dhcp6.callouts/101535.139952525041536] HOOKS_CALLOUT_REGISTRATION hooks library with index 2 registering callout for hook '$lease4_wipe'
2022-12-13 08:53:49.771 DEBUG [kea-dhcp6.callouts/101535.139952525041536] HOOKS_CALLOUT_REGISTRATION hooks library with index 2 registering callout for hook '$lease6_wipe'
2022-12-13 08:53:49.771 DEBUG [kea-dhcp6.callouts/101535.139952525041536] HOOKS_CALLOUT_REGISTRATION hooks library with index 2 registering callout for hook '$lease4_resend_ddns'
2022-12-13 08:53:49.772 DEBUG [kea-dhcp6.callouts/101535.139952525041536] HOOKS_CALLOUT_REGISTRATION hooks library with index 2 registering callout for hook '$lease6_resend_ddns'
2022-12-13 08:53:49.772 DEBUG [kea-dhcp6.callouts/101535.139952525041536] HOOKS_CALLOUT_REGISTRATION hooks library with index 2 registering callout for hook '$lease4_write'
2022-12-13 08:53:49.772 DEBUG [kea-dhcp6.callouts/101535.139952525041536] HOOKS_CALLOUT_REGISTRATION hooks library with index 2 registering callout for hook '$lease6_write'
2022-12-13 08:53:49.772 INFO [kea-dhcp6.lease-cmds-hooks/101535.139952525041536] LEASE_CMDS_INIT_OK loading Lease Commands hooks library successful
2022-12-13 08:53:49.772 DEBUG [kea-dhcp6.hooks/101535.139952525041536] HOOKS_LOAD_SUCCESS 'load' function in hook library /home/wlodek/installed/git/lib/kea/hooks/libdhcp_lease_cmds.so returned success
2022-12-13 08:53:49.772 INFO [kea-dhcp6.hooks/101535.139952525041536] HOOKS_LIBRARY_LOADED hooks library /home/wlodek/installed/git/lib/kea/hooks/libdhcp_lease_cmds.so successfully loaded
2022-12-13 08:53:49.773 INFO [kea-dhcp6.dhcp6/101535.139952525041536] DHCP6_CONFIG_COMPLETE DHCPv6 server has completed configuration: added IPv6 subnets: 1; DDNS: disabled
2022-12-13 08:53:49.773 INFO [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_MEMFILE_DB opening memory file lease database: extended-info-tables=true type=memfile universe=6
2022-12-13 08:53:49.773 INFO [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_MEMFILE_LEASE_FILE_LOAD loading leases from file /home/wlodek/installed/git/var/lib/kea/kea-leases6.csv
2022-12-13 08:53:49.773 DEBUG [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_MEMFILE_LEASE_LOAD loading lease Type: IA_NA(0)
Address: 2001:db8:1::1
Prefix length: 128
IAID: 38925
Pref life: 8000000
Valid life: 9000000
Cltt: 1670949131
DUID: 00:03:00:01:01:03:0d:04:0b:01
Hardware addr: 01:03:0d:04:0b:01
Subnet ID: 1
State: default
User context: { "ISC": { "relay-info": [ { "hop": 0, "link": "2001:db8:1::1000", "options": "0x0012000231350025000A000000000A0027000001", "peer": "fe80::800:27ff:fe00:2", "remote-id": "000000000A0027000001" } ] } }
2022-12-13 08:53:49.773 DEBUG [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_MEMFILE_LEASE_LOAD loading lease Type: IA_NA(0)
Address: 2001:db8:1::2
Prefix length: 128
IAID: 38925
Pref life: 8000000
Valid life: 9000000
Cltt: 1670949131
DUID: 00:03:00:01:01:04:0e:05:0c:02
Hardware addr: 01:04:0e:05:0c:02
Subnet ID: 1
State: default
User context: { "ISC": { "relay-info": [ { "hop": 0, "link": "2001:db8:1::1000", "options": "0x0012000231350025000A000000000A0027000001", "peer": "fe80::800:27ff:fe00:2", "remote-id": "000000000A0027000001" } ] } }
2022-12-13 08:53:49.773 DEBUG [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_MEMFILE_LEASE_LOAD loading lease Type: IA_NA(0)
Address: 2001:db8:1::3
Prefix length: 128
IAID: 38925
Pref life: 8000000
Valid life: 9000000
Cltt: 1670949132
DUID: 00:03:00:01:01:05:0f:06:0d:03
Hardware addr: 01:05:0f:06:0d:03
Subnet ID: 1
State: default
User context: { "ISC": { "relay-info": [ { "hop": 0, "link": "2001:db8:1::1000", "options": "0x0012000231350025000A000000000A0027000001", "peer": "fe80::800:27ff:fe00:2", "remote-id": "000000000A0027000001" } ] } }
2022-12-13 08:53:49.773 DEBUG [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_MEMFILE_LEASE_LOAD loading lease Type: IA_NA(0)
Address: 2001:db8:1::4
Prefix length: 128
IAID: 38925
Pref life: 8000000
Valid life: 9000000
Cltt: 1670949133
DUID: 00:03:00:01:01:06:10:07:0e:04
Hardware addr: 01:06:10:07:0e:04
Subnet ID: 1
State: default
User context: { "ISC": { "relay-info": [ { "hop": 0, "link": "2001:db8:1::1000", "options": "0x0012000231350025000A000000000A0027000001", "peer": "fe80::800:27ff:fe00:2", "remote-id": "000000000A0027000001" } ] } }
2022-12-13 08:53:49.774 DEBUG [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_MEMFILE_LEASE_LOAD loading lease Type: IA_NA(0)
Address: 2001:db8:1::5
Prefix length: 128
IAID: 38925
Pref life: 8000000
Valid life: 9000000
Cltt: 1670949133
DUID: 00:03:00:01:01:07:11:08:0f:05
Hardware addr: 01:07:11:08:0f:05
Subnet ID: 1
State: default
User context: { "ISC": { "relay-info": [ { "hop": 0, "link": "2001:db8:1::1000", "options": "0x0012000231350025000A000000000A0027000001", "peer": "fe80::800:27ff:fe00:2", "remote-id": "000000000A0027000001" } ] } }
2022-12-13 08:53:49.774 DEBUG [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_MEMFILE_LEASE_LOAD loading lease Type: IA_NA(0)
Address: 2001:db8:1::6
Prefix length: 128
IAID: 38925
Pref life: 8000000
Valid life: 9000000
Cltt: 1670949134
DUID: 00:03:00:01:01:08:12:09:10:06
Hardware addr: 01:08:12:09:10:06
Subnet ID: 1
State: default
User context: { "ISC": { "relay-info": [ { "hop": 0, "link": "2001:db8:1::1000", "options": "0x0012000231350025000A000000000A0027000001", "peer": "fe80::800:27ff:fe00:2", "remote-id": "000000000A0027000001" } ] } }
2022-12-13 08:53:49.774 DEBUG [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_MEMFILE_LEASE_LOAD loading lease Type: IA_NA(0)
Address: 2001:db8:1::7
Prefix length: 128
IAID: 38925
Pref life: 8000000
Valid life: 9000000
Cltt: 1670949135
DUID: 00:03:00:01:01:09:13:0a:11:07
Hardware addr: 01:09:13:0a:11:07
Subnet ID: 1
State: default
User context: { "ISC": { "relay-info": [ { "hop": 0, "link": "2001:db8:1::1000", "options": "0x0012000231350025000A000000000A0027000001", "peer": "fe80::800:27ff:fe00:2", "remote-id": "000000000A0027000001" } ] } }
2022-12-13 08:53:49.774 DEBUG [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_MEMFILE_LEASE_LOAD loading lease Type: IA_NA(0)
Address: 2001:db8:1::8
Prefix length: 128
IAID: 38925
Pref life: 8000000
Valid life: 9000000
Cltt: 1670949136
DUID: 00:03:00:01:01:0a:14:0b:12:08
Hardware addr: 01:0a:14:0b:12:08
Subnet ID: 1
State: default
User context: { "ISC": { "relay-info": [ { "hop": 0, "link": "2001:db8:1::1000", "options": "0x0012000231350025000A000000000A0027000001", "peer": "fe80::800:27ff:fe00:2", "remote-id": "000000000A0027000001" } ] } }
2022-12-13 08:53:49.774 DEBUG [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_MEMFILE_LEASE_LOAD loading lease Type: IA_NA(0)
Address: 2001:db8:1::9
Prefix length: 128
IAID: 38925
Pref life: 8000000
Valid life: 9000000
Cltt: 1670949137
DUID: 00:03:00:01:01:0b:15:0c:13:09
Hardware addr: 01:0b:15:0c:13:09
Subnet ID: 1
State: default
User context: { "ISC": { "relay-info": [ { "hop": 0, "link": "2001:db8:1::1000", "options": "0x0012000231350025000A000000000A0027000001", "peer": "fe80::800:27ff:fe00:2", "remote-id": "000000000A0027000001" } ] } }
2022-12-13 08:53:49.774 DEBUG [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_MEMFILE_LEASE_LOAD loading lease Type: IA_NA(0)
Address: 2001:db8:1::a
Prefix length: 128
IAID: 38925
Pref life: 8000000
Valid life: 9000000
Cltt: 1670949137
DUID: 00:03:00:01:01:0c:16:0d:14:0a
Hardware addr: 01:0c:16:0d:14:0a
Subnet ID: 1
State: default
User context: { "ISC": { "relay-info": [ { "hop": 0, "link": "2001:db8:1::1000", "options": "0x0012000231350025000A000000000A0027000001", "peer": "fe80::800:27ff:fe00:2", "remote-id": "000000000A0027000001" } ] } }
2022-12-13 08:53:49.774 DEBUG [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_MEMFILE_LEASE_LOAD loading lease Type: IA_NA(0)
Address: 2001:db8:1::b
Prefix length: 128
IAID: 38925
Pref life: 8000000
Valid life: 9000000
Cltt: 1670949138
DUID: 00:03:00:01:01:0d:17:0e:15:0b
Hardware addr: 01:0d:17:0e:15:0b
Subnet ID: 1
State: default
User context: { "ISC": { "relay-info": [ { "hop": 0, "link": "2001:db8:1::1000", "options": "0x0012000231350025000A000000000A0027000001", "peer": "fe80::800:27ff:fe00:2", "remote-id": "000000000A0027000001" } ] } }
2022-12-13 08:53:49.774 DEBUG [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_MEMFILE_LEASE_LOAD loading lease Type: IA_NA(0)
Address: 2001:db8:1::c
Prefix length: 128
IAID: 38925
Pref life: 8000000
Valid life: 9000000
Cltt: 1670949138
DUID: 00:03:00:01:01:0e:18:0f:16:0c
Hardware addr: 01:0e:18:0f:16:0c
Subnet ID: 1
State: default
User context: { "ISC": { "relay-info": [ { "hop": 0, "link": "2001:db8:1::1000", "options": "0x0012000231350025000A000000000A0027000001", "peer": "fe80::800:27ff:fe00:2", "remote-id": "000000000A0027000001" } ] } }
2022-12-13 08:53:49.774 DEBUG [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_MEMFILE_LEASE_LOAD loading lease Type: IA_NA(0)
Address: 2001:db8:1::d
Prefix length: 128
IAID: 38925
Pref life: 8000000
Valid life: 9000000
Cltt: 1670949138
DUID: 00:03:00:01:01:0f:19:10:17:0d
Hardware addr: 01:0f:19:10:17:0d
Subnet ID: 1
State: default
User context: { "ISC": { "relay-info": [ { "hop": 0, "link": "2001:db8:1::1000", "options": "0x0012000231350025000A000000000A0027000001", "peer": "fe80::800:27ff:fe00:2", "remote-id": "000000000A0027000001" } ] } }
2022-12-13 08:53:49.774 DEBUG [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_MEMFILE_LEASE_LOAD loading lease Type: IA_NA(0)
Address: 2001:db8:1::e
Prefix length: 128
IAID: 38925
Pref life: 8000000
Valid life: 9000000
Cltt: 1670949139
DUID: 00:03:00:01:01:10:1a:11:18:0e
Hardware addr: 01:10:1a:11:18:0e
Subnet ID: 1
State: default
User context: { "ISC": { "relay-info": [ { "hop": 0, "link": "2001:db8:1::1000", "options": "0x0012000231350025000A000000000A0027000001", "peer": "fe80::800:27ff:fe00:2", "remote-id": "000000000A0027000001" } ] } }
2022-12-13 08:53:49.774 DEBUG [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_MEMFILE_LEASE_LOAD loading lease Type: IA_NA(0)
Address: 2001:db8:1::f
Prefix length: 128
IAID: 38925
Pref life: 8000000
Valid life: 9000000
Cltt: 1670949139
DUID: 00:03:00:01:01:11:1b:12:19:0f
Hardware addr: 01:11:1b:12:19:0f
Subnet ID: 1
State: default
User context: { "ISC": { "relay-info": [ { "hop": 0, "link": "2001:db8:1::1000", "options": "0x0012000231350025000A000000000A0027000001", "peer": "fe80::800:27ff:fe00:2", "remote-id": "000000000A0027000001" } ] } }
2022-12-13 08:53:49.774 DEBUG [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_MEMFILE_LEASE_LOAD loading lease Type: IA_NA(0)
Address: 2001:db8:1::10
Prefix length: 128
IAID: 38925
Pref life: 8000000
Valid life: 9000000
Cltt: 1670949139
DUID: 00:03:00:01:01:12:1c:13:1a:10
Hardware addr: 01:12:1c:13:1a:10
Subnet ID: 1
State: default
User context: { "ISC": { "relay-info": [ { "hop": 0, "link": "2001:db8:1::1000", "options": "0x0012000231350025000A000000000A0027000001", "peer": "fe80::800:27ff:fe00:2", "remote-id": "000000000A0027000001" } ] } }
2022-12-13 08:53:49.774 DEBUG [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_MEMFILE_LEASE_LOAD loading lease Type: IA_NA(0)
Address: 2001:db8:1::11
Prefix length: 128
IAID: 38925
Pref life: 8000000
Valid life: 9000000
Cltt: 1670949140
DUID: 00:03:00:01:01:13:1d:14:1b:11
Hardware addr: 01:13:1d:14:1b:11
Subnet ID: 1
State: default
User context: { "ISC": { "relay-info": [ { "hop": 0, "link": "2001:db8:1::1000", "options": "0x0012000231350025000A000000000A0027000001", "peer": "fe80::800:27ff:fe00:2", "remote-id": "000000000A0027000001" } ] } }
2022-12-13 08:53:49.774 DEBUG [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_MEMFILE_LEASE_LOAD loading lease Type: IA_NA(0)
Address: 2001:db8:1::12
Prefix length: 128
IAID: 38925
Pref life: 8000000
Valid life: 9000000
Cltt: 1670949140
DUID: 00:03:00:01:01:14:1e:15:1c:12
Hardware addr: 01:14:1e:15:1c:12
Subnet ID: 1
State: default
User context: { "ISC": { "relay-info": [ { "hop": 0, "link": "2001:db8:1::1000", "options": "0x0012000231350025000A000000000A0027000001", "peer": "fe80::800:27ff:fe00:2", "remote-id": "000000000A0027000001" } ] } }
2022-12-13 08:53:49.774 DEBUG [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_MEMFILE_LEASE_LOAD loading lease Type: IA_NA(0)
Address: 2001:db8:1::13
Prefix length: 128
IAID: 38925
Pref life: 8000000
Valid life: 9000000
Cltt: 1670949141
DUID: 00:03:00:01:01:15:1f:16:1d:13
Hardware addr: 01:15:1f:16:1d:13
Subnet ID: 1
State: default
User context: { "ISC": { "relay-info": [ { "hop": 0, "link": "2001:db8:1::1000", "options": "0x0012000231350025000A000000000A0027000001", "peer": "fe80::800:27ff:fe00:2", "remote-id": "000000000A0027000001" } ] } }
2022-12-13 08:53:49.775 DEBUG [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_MEMFILE_LEASE_LOAD loading lease Type: IA_NA(0)
Address: 2001:db8:1::14
Prefix length: 128
IAID: 38925
Pref life: 8000000
Valid life: 9000000
Cltt: 1670949141
DUID: 00:03:00:01:01:16:20:17:1e:14
Hardware addr: 01:16:20:17:1e:14
Subnet ID: 1
State: default
User context: { "ISC": { "relay-info": [ { "hop": 0, "link": "2001:db8:1::1000", "options": "0x0012000231350025000A000000000A0027000001", "peer": "fe80::800:27ff:fe00:2", "remote-id": "000000000A0027000001" } ] } }
2022-12-13 08:53:49.775 DEBUG [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_MEMFILE_BEGIN_BUILD_EXTENDED_INFO_TABLES6 building extended info tables with fix sanity check level, tables enabled
2022-12-13 08:53:49.775 INFO [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_MEMFILE_BUILD_EXTENDED_INFO_TABLES6 building extended info tables saw 20 leases, extended info sanity checks modified 0 / updated 0 leases and 20 leases were entered into tables
2022-12-13 08:53:49.775 INFO [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_MEMFILE_LFC_SETUP setting up the Lease File Cleanup interval to 3600 sec
2022-12-13 08:53:49.775 DEBUG [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_TIMERMGR_REGISTER_TIMER registering timer: memfile-lfc, using interval: 3600000 ms
2022-12-13 08:53:49.775 DEBUG [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_TIMERMGR_START_TIMER starting timer: memfile-lfc
2022-12-13 08:53:49.775 INFO [kea-dhcp6.dhcp6/101535.139952525041536] DHCP6_USING_SERVERID server is using server-id 00:01:00:01:2b:2b:65:87:08:00:27:4a:04:65 and stores in the file /home/wlodek/installed/git/var/lib/kea/kea-dhcp6-serverid
2022-12-13 08:53:49.776 DEBUG [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_TIMERMGR_REGISTER_TIMER registering timer: reclaim-expired-leases, using interval: 50000000 ms
2022-12-13 08:53:49.776 DEBUG [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_TIMERMGR_START_TIMER starting timer: reclaim-expired-leases
2022-12-13 08:53:49.776 DEBUG [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_TIMERMGR_REGISTER_TIMER registering timer: flush-reclaimed-leases, using interval: 50000000 ms
2022-12-13 08:53:49.776 DEBUG [kea-dhcp6.dhcpsrv/101535.139952525041536] DHCPSRV_TIMERMGR_START_TIMER starting timer: flush-reclaimed-leases
2022-12-13 08:53:49.776 DEBUG [kea-dhcp6.callouts/101535.139952525041536] HOOKS_CALLOUTS_BEGIN begin all callouts for hook dhcp6_srv_configured
2022-12-13 08:53:49.776 DEBUG [kea-dhcp6.tcp/101535.139952525041536] MT_TCP_LISTENER_MGR_STARTED MtTcpListenerMgr started with 2 threads, listening on 2001:db8:1::1000:9000, use TLS: false
2022-12-13 08:53:49.776 DEBUG [kea-dhcp6.callouts/101535.139952525041536] HOOKS_CALLOUT_CALLED hooks library with index 1 has called a callout on hook dhcp6_srv_configured that has address 0x7f493a851910 (callout duration: 0.272 ms)
2022-12-13 08:53:49.776 DEBUG [kea-dhcp6.callouts/101535.139952525041536] HOOKS_CALLOUTS_COMPLETE completed callouts for hook dhcp6_srv_configured (total callouts duration: 0.272 ms)
2022-12-13 08:53:49.776 WARN [kea-dhcp6.dhcp6/101535.139952525041536] DHCP6_MULTI_THREADING_INFO enabled: yes, number of threads: 2, queue size: 16
2022-12-13 08:53:49.776 INFO [kea-dhcp6.dhcp6/101535.139952525041536] DHCP6_STARTED Kea DHCPv6 server version 2.3.4-git started
2022-12-13 08:53:52.126 DEBUG [kea-dhcp6.tcp/101535.139952490108672] TCP_REQUEST_RECEIVE_START start receiving request from 2001:db8:1::2000 with timeout 300
2022-12-13 08:53:52.126 DEBUG [kea-dhcp6.tcp/101535.139952490108672] TCP_DATA_RECEIVED received 69 bytes from 2001:db8:1::2000
2022-12-13 08:53:52.127 DEBUG [kea-dhcp6.tcp/101535.139952490108672] TCP_CLIENT_REQUEST_RECEIVED received TCP request from 2001:db8:1::2000
2022-12-13 08:53:52.127 DEBUG [kea-dhcp6.dhcpsrv/101535.139952490108672] DHCPSRV_MEMFILE_GET_REMOTEID6 obtaining at most 5 IPv6 leases starting from address :: with remote id 00:00:00:00:0a:00:27:00:00:01 and link 2001:db8:1::2000/64
2022-12-13 08:53:52.127 DEBUG [kea-dhcp6.dhcpsrv/101535.139952490108672] DHCPSRV_MEMFILE_GET_REMOTEID6 obtaining at most 5 IPv6 leases starting from address 2001:db8:1::5 with remote id 00:00:00:00:0a:00:27:00:00:01 and link 2001:db8:1::2000/64
2022-12-13 08:53:52.128 DEBUG [kea-dhcp6.tcp/101535.139952481715968] TCP_CONNECTION_STOP stopping TCP connection from 2001:db8:1::2000
2022-12-13 08:53:52.128 DEBUG [kea-dhcp6.tcp/101535.139952481715968] TCP_CONNECTION_STOP stopping TCP connection from (unknown address)
2022-12-13 08:53:52.128 DEBUG [kea-dhcp6.tcp/101535.139952481715968] TCP_CONNECTION_STOP stopping TCP connection from (unknown address)
[1]+ Segmentation fault sudo /home/wlodek/installed/git/sbin/kea-dhcp6 -c /home/wlodek/installed/git/etc/kea/kea-dhcp6.confBacktrace of all threads:
Core was generated by `/home/wlodek/installed/git/sbin/kea-dhcp6 -c /home/wlodek/installed/git/etc/kea'.
Program terminated with signal SIGSEGV, Segmentation fault.
#0 0x00007f17b400ae40 in ?? ()
[Current thread is 1 (Thread 0x7f17babf7700 (LWP 72463))]
(gdb) thread apply all bt
Thread 9 (Thread 0x7f17ba3f6700 (LWP 72464)):
#0 futex_wait_cancelable (private=0, expected=0, futex_word=0x7f17be9673b0 <isc::util::MultiThreadingMgr::instance()::manager+208>) at ../sysdeps/nptl/futex-internal.h:186
#1 __pthread_cond_wait_common (abstime=0x0, clockid=0, mutex=0x7f17be967360 <isc::util::MultiThreadingMgr::instance()::manager+128>, cond=0x7f17be967388 <isc::util::MultiThreadingMgr::instance()::manager+168>) at pthread_cond_wait.c:508
#2 __pthread_cond_wait (cond=0x7f17be967388 <isc::util::MultiThreadingMgr::instance()::manager+168>, mutex=0x7f17be967360 <isc::util::MultiThreadingMgr::instance()::manager+128>) at pthread_cond_wait.c:638
#3 0x00007f17be7e190c in std::condition_variable::wait(std::unique_lock<std::mutex>&) () from /usr/lib/x86_64-linux-gnu/libstdc++.so.6
#4 0x00007f17be926819 in std::condition_variable::wait<isc::util::ThreadPool<std::function<void ()>, std::deque<boost::shared_ptr<std::function<void ()> >, std::allocator<boost::shared_ptr<std::function<void ()> > > > >::ThreadPoolQueue<boost::shared_ptr<std::function<void ()> >, std::deque<boost::shared_ptr<std::function<void ()> >, std::allocator<boost::shared_ptr<std::function<void ()> > > > >::pop()::{lambda()#1}>(std::unique_lock<std::mutex>&, isc::util::ThreadPool<std::function<void ()>, std::deque<boost::shared_ptr<std::function<void ()> >, std::allocator<boost::shared_ptr<std::function<void ()> > > > >::ThreadPoolQueue<boost::shared_ptr<std::function<void ()> >, std::deque<boost::shared_ptr<std::function<void ()> >, std::allocator<boost::shared_ptr<std::function<void ()> > > > >::pop()::{lambda()#1}) (__p=..., __lock=..., this=0x7f17be967388 <isc::util::MultiThreadingMgr::instance()::manager+168>) at /usr/include/c++/10/condition_variable:111
#5 isc::util::ThreadPool<std::function<void ()>, std::deque<boost::shared_ptr<std::function<void ()> >, std::allocator<boost::shared_ptr<std::function<void ()> > > > >::ThreadPoolQueue<boost::shared_ptr<std::function<void ()> >, std::deque<boost::shared_ptr<std::function<void ()> >, std::allocator<boost::shared_ptr<std::function<void ()> > > > >::pop() (this=0x7f17be967310 <isc::util::MultiThreadingMgr::instance()::manager+48>) at ../../../src/lib/util/thread_pool.h:345
#6 isc::util::ThreadPool<std::function<void ()>, std::deque<boost::shared_ptr<std::function<void ()> >, std::allocator<boost::shared_ptr<std::function<void ()> > > > >::run() (this=0x7f17be9672f8 <isc::util::MultiThreadingMgr::instance()::manager+24>) at ../../../src/lib/util/thread_pool.h:494
#7 0x00007f17be7e6ed0 in ?? () from /usr/lib/x86_64-linux-gnu/libstdc++.so.6
#8 0x00007f17bf2c2ea7 in start_thread (arg=<optimized out>) at pthread_create.c:477
#9 0x00007f17be4dfaef in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
Thread 8 (Thread 0x7f17bc53b700 (LWP 72460)):
#0 futex_wait_cancelable (private=0, expected=0, futex_word=0x5587a225bff0) at ../sysdeps/nptl/futex-internal.h:186
#1 __pthread_cond_wait_common (abstime=0x0, clockid=0, mutex=0x5587a225bf70, cond=0x5587a225bfc8) at pthread_cond_wait.c:508
#2 __pthread_cond_wait (cond=0x5587a225bfc8, mutex=0x5587a225bf70) at pthread_cond_wait.c:638
#3 0x00007f17be7e190c in std::condition_variable::wait(std::unique_lock<std::mutex>&) () from /usr/lib/x86_64-linux-gnu/libstdc++.so.6
#4 0x00007f17bddf3a29 in progschj::ThreadPool::emplace_back_worker(unsigned long)::{lambda()#1}::operator()() const () from /usr/lib/x86_64-linux-gnu/liblog4cplus-2.0.so.3
#5 0x00007f17be7e6ed0 in ?? () from /usr/lib/x86_64-linux-gnu/libstdc++.so.6
#6 0x00007f17bf2c2ea7 in start_thread (arg=<optimized out>) at pthread_create.c:477
#7 0x00007f17be4dfaef in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
Thread 7 (Thread 0x7f17bbd3a700 (LWP 72461)):
#0 futex_wait_cancelable (private=0, expected=0, futex_word=0x5587a225bff0) at ../sysdeps/nptl/futex-internal.h:186
#1 __pthread_cond_wait_common (abstime=0x0, clockid=0, mutex=0x5587a225bf70, cond=0x5587a225bfc8) at pthread_cond_wait.c:508
#2 __pthread_cond_wait (cond=0x5587a225bfc8, mutex=0x5587a225bf70) at pthread_cond_wait.c:638
#3 0x00007f17be7e190c in std::condition_variable::wait(std::unique_lock<std::mutex>&) () from /usr/lib/x86_64-linux-gnu/libstdc++.so.6
#4 0x00007f17bddf3a29 in progschj::ThreadPool::emplace_back_worker(unsigned long)::{lambda()#1}::operator()() const () from /usr/lib/x86_64-linux-gnu/liblog4cplus-2.0.so.3
#5 0x00007f17be7e6ed0 in ?? () from /usr/lib/x86_64-linux-gnu/libstdc++.so.6
#6 0x00007f17bf2c2ea7 in start_thread (arg=<optimized out>) at pthread_create.c:477
#7 0x00007f17be4dfaef in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
Thread 6 (Thread 0x7f17bd548f80 (LWP 72457)):
#0 0x00007f17be4d62a3 in __GI___select (nfds=19, readfds=readfds@entry=0x7ffeaf319cb0, writefds=writefds@entry=0x0, exceptfds=exceptfds@entry=0x0, timeout=timeout@entry=0x7ffeaf319c50) at ../sysdeps/unix/sysv/linux/select.c:41
#1 0x00007f17becfc045 in isc::dhcp::IfaceMgr::receive6Direct (this=0x5587a22b65e0, timeout_sec=1, timeout_usec=0) at iface_mgr.cc:1471
#2 0x00007f17becfcbf5 in isc::dhcp::IfaceMgr::receive6 (this=<optimized out>, timeout_sec=timeout_sec@entry=1, timeout_usec=timeout_usec@entry=0) at iface_mgr.cc:1411
#3 0x00005587a1fd4606 in isc::dhcp::Dhcpv6Srv::receivePacket (timeout=1, this=0x7ffeaf31a250) at dhcp6_srv.cc:311
#4 isc::dhcp::Dhcpv6Srv::run_one (this=0x7ffeaf31a250) at dhcp6_srv.cc:637
#5 0x00005587a1fd4ad8 in isc::dhcp::Dhcpv6Srv::run (this=0x7ffeaf31a250) at dhcp6_srv.cc:604
#6 0x00005587a1fafe18 in main (argc=<optimized out>, argv=0x7ffeaf31a250) at main.cc:270
Thread 5 (Thread 0x7f17bd53d700 (LWP 72458)):
#0 futex_wait_cancelable (private=0, expected=0, futex_word=0x5587a225bff0) at ../sysdeps/nptl/futex-internal.h:186
#1 __pthread_cond_wait_common (abstime=0x0, clockid=0, mutex=0x5587a225bf70, cond=0x5587a225bfc8) at pthread_cond_wait.c:508
#2 __pthread_cond_wait (cond=0x5587a225bfc8, mutex=0x5587a225bf70) at pthread_cond_wait.c:638
#3 0x00007f17be7e190c in std::condition_variable::wait(std::unique_lock<std::mutex>&) () from /usr/lib/x86_64-linux-gnu/libstdc++.so.6
#4 0x00007f17bddf3a29 in progschj::ThreadPool::emplace_back_worker(unsigned long)::{lambda()#1}::operator()() const () from /usr/lib/x86_64-linux-gnu/liblog4cplus-2.0.so.3
#5 0x00007f17be7e6ed0 in ?? () from /usr/lib/x86_64-linux-gnu/libstdc++.so.6
#6 0x00007f17bf2c2ea7 in start_thread (arg=<optimized out>) at pthread_create.c:477
#7 0x00007f17be4dfaef in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
Thread 4 (Thread 0x7f17b9bf5700 (LWP 72465)):
#0 futex_wait_cancelable (private=0, expected=0, futex_word=0x7f17be9673b0 <isc::util::MultiThreadingMgr::instance()::manager+208>) at ../sysdeps/nptl/futex-internal.h:186
#1 __pthread_cond_wait_common (abstime=0x0, clockid=0, mutex=0x7f17be967360 <isc::util::MultiThreadingMgr::instance()::manager+128>, cond=0x7f17be967388 <isc::util::MultiThreadingMgr::instance()::manager+168>) at pthread_cond_wait.c:508
#2 __pthread_cond_wait (cond=0x7f17be967388 <isc::util::MultiThreadingMgr::instance()::manager+168>, mutex=0x7f17be967360 <isc::util::MultiThreadingMgr::instance()::manager+128>) at pthread_cond_wait.c:638
#3 0x00007f17be7e190c in std::condition_variable::wait(std::unique_lock<std::mutex>&) () from /usr/lib/x86_64-linux-gnu/libstdc++.so.6
#4 0x00007f17be926819 in std::condition_variable::wait<isc::util::ThreadPool<std::function<void ()>, std::deque<boost::shared_ptr<std::function<void ()> >, std::allocator<boost::shared_ptr<std::function<void ()> > > > >::ThreadPoolQueue<boost::shared_ptr<std::function<void ()> >, std::deque<boost::shared_ptr<std::function<void ()> >, std::allocator<boost::shared_ptr<std::function<void ()> > > > >::pop()::{lambda()#1}>(std::unique_lock<std::mutex>&, isc::util::ThreadPool<std::function<void ()>, std::deque<boost::shared_ptr<std::function<void ()> >, std::allocator<boost::shared_ptr<std::function<void ()> > > > >::ThreadPoolQueue<boost::shared_ptr<std::function<void ()> >, std::deque<boost::shared_ptr<std::function<void ()> >, std::allocator<boost::shared_ptr<std::function<void ()> > > > >::pop()::{lambda()#1}) (__p=..., __lock=..., this=0x7f17be967388 <isc::util::MultiThreadingMgr::instance()::manager+168>) at /usr/include/c++/10/condition_variable:111
#5 isc::util::ThreadPool<std::function<void ()>, std::deque<boost::shared_ptr<std::function<void ()> >, std::allocator<boost::shared_ptr<std::function<void ()> > > > >::ThreadPoolQueue<boost::shared_ptr<std::function<void ()> >, std::deque<boost::shared_ptr<std::function<void ()> >, std::allocator<boost::shared_ptr<std::function<void ()> > > > >::pop() (this=0x7f17be967310 <isc::util::MultiThreadingMgr::instance()::manager+48>) at ../../../src/lib/util/thread_pool.h:345
#6 isc::util::ThreadPool<std::function<void ()>, std::deque<boost::shared_ptr<std::function<void ()> >, std::allocator<boost::shared_ptr<std::function<void ()> > > > >::run() (this=0x7f17be9672f8 <isc::util::MultiThreadingMgr::instance()::manager+24>) at ../../../src/lib/util/thread_pool.h:494
#7 0x00007f17be7e6ed0 in ?? () from /usr/lib/x86_64-linux-gnu/libstdc++.so.6
#8 0x00007f17bf2c2ea7 in start_thread (arg=<optimized out>) at pthread_create.c:477
#9 0x00007f17be4dfaef in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
Thread 3 (Thread 0x7f17bb3f8700 (LWP 72462)):
#0 0x00007f17be4dfe16 in epoll_wait (epfd=20, events=events@entry=0x7f17bb3f7480, maxevents=maxevents@entry=128, timeout=-1) at ../sysdeps/unix/sysv/linux/epoll_wait.c:30
#1 0x00007f17bee6a004 in boost::asio::detail::epoll_reactor::run (this=0x5587a22e8420, usec=<optimized out>, ops=...) at /usr/include/boost/asio/detail/impl/epoll_reactor.ipp:471
#2 0x00007f17bee787f7 in boost::asio::detail::scheduler::do_run_one (this=0x5587a22b6380, lock=..., this_thread=..., ec=...) at /usr/include/boost/asio/detail/impl/scheduler.ipp:465
#3 0x00007f17bec10419 in boost::asio::detail::scheduler::run (this=0x5587a22b6380, ec=...) at /usr/include/boost/asio/detail/impl/scheduler.ipp:204
#4 0x00007f17bec10e44 in boost::asio::io_context::run (this=<optimized out>, this=<optimized out>) at /usr/include/boost/asio/impl/io_context.ipp:63
#5 isc::asiolink::IOServiceImpl::run (this=<optimized out>) at io_service.cc:40
#6 isc::asiolink::IOService::run (this=<optimized out>) at io_service.cc:115
#7 0x00007f17bec165db in isc::asiolink::IoServiceThreadPool::threadWork (this=0x5587a22e85b0) at io_service_thread_pool.cc:219
#8 0x00007f17be7e6ed0 in ?? () from /usr/lib/x86_64-linux-gnu/libstdc++.so.6
#9 0x00007f17bf2c2ea7 in start_thread (arg=<optimized out>) at pthread_create.c:477
#10 0x00007f17be4dfaef in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
Thread 2 (Thread 0x7f17bcd3c700 (LWP 72459)):
#0 futex_wait_cancelable (private=0, expected=0, futex_word=0x5587a225bff0) at ../sysdeps/nptl/futex-internal.h:186
#1 __pthread_cond_wait_common (abstime=0x0, clockid=0, mutex=0x5587a225bf70, cond=0x5587a225bfc8) at pthread_cond_wait.c:508
#2 __pthread_cond_wait (cond=0x5587a225bfc8, mutex=0x5587a225bf70) at pthread_cond_wait.c:638
#3 0x00007f17be7e190c in std::condition_variable::wait(std::unique_lock<std::mutex>&) () from /usr/lib/x86_64-linux-gnu/libstdc++.so.6
#4 0x00007f17bddf3a29 in progschj::ThreadPool::emplace_back_worker(unsigned long)::{lambda()#1}::operator()() const () from /usr/lib/x86_64-linux-gnu/liblog4cplus-2.0.so.3
#5 0x00007f17be7e6ed0 in ?? () from /usr/lib/x86_64-linux-gnu/libstdc++.so.6
#6 0x00007f17bf2c2ea7 in start_thread (arg=<optimized out>) at pthread_create.c:477
#7 0x00007f17be4dfaef in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
Thread 1 (Thread 0x7f17babf7700 (LWP 72463)):
#0 0x00007f17b400ae40 in ?? ()
#1 0x00007f17bb518eeb in isc::lease_query::BulkLeaseQuery6::bulkQueryByRemoteIdNext (this=0x7f17b400c220) at bulk_lease_query6.cc:441
#2 0x00007f17bb5192ec in isc::lease_query::BulkLeaseQuery6::bulkQueryByRemoteIdNextNoThrow (this=<optimized out>) at bulk_lease_query6.cc:415
#3 0x00007f17bec11701 in std::function<void ()>::operator()() const (this=0x7f17babf6a70) at /usr/include/c++/10/bits/std_function.h:622
--Type <RET> for more, q to quit, c to continue without paging--
#4 boost::asio::asio_handler_invoke<std::function<void ()> >(std::function<void ()>&, ...) (function=...) at /usr/include/boost/asio/handler_invoke_hook.hpp:88
#5 boost_asio_handler_invoke_helpers::invoke<std::function<void ()>, std::function<void ()> >(std::function<void ()>&, std::function<void ()>&) (context=..., function=...) at /usr/include/boost/asio/detail/handler_invoke_helpers.hpp:54
#6 boost::asio::detail::handler_work<std::function<void ()>, boost::asio::io_context::basic_executor_type<std::allocator<void>, 0u>, void>::complete<std::function<void ()> >(std::function<void ()>&, std::function<void ()>&) (handler=..., function=..., this=<synthetic pointer>) at /usr/include/boost/asio/detail/handler_work.hpp:425
#7 boost::asio::detail::completion_handler<std::function<void ()>, boost::asio::io_context::basic_executor_type<std::allocator<void>, 0u> >::do_complete(void*, boost::asio::detail::scheduler_operation*, boost::system::error_code const&, unsigned long) (owner=0x5587a22b6380, base=0x7f17babf6a70) at /usr/include/boost/asio/detail/completion_handler.hpp:74
#8 0x00007f17bee78b04 in boost::asio::detail::scheduler_operation::complete (bytes_transferred=<optimized out>, ec=..., owner=0x5587a22b6380, this=0x7f17b401df10) at /usr/include/boost/asio/detail/scheduler_operation.hpp:40
#9 boost::asio::detail::scheduler::do_run_one (this=0x5587a22b6380, lock=..., this_thread=..., ec=...) at /usr/include/boost/asio/detail/impl/scheduler.ipp:481
#10 0x00007f17bec10419 in boost::asio::detail::scheduler::run (this=0x5587a22b6380, ec=...) at /usr/include/boost/asio/detail/impl/scheduler.ipp:204
#11 0x00007f17bec10e44 in boost::asio::io_context::run (this=<optimized out>, this=<optimized out>) at /usr/include/boost/asio/impl/io_context.ipp:63
#12 isc::asiolink::IOServiceImpl::run (this=<optimized out>) at io_service.cc:40
#13 isc::asiolink::IOService::run (this=<optimized out>) at io_service.cc:115
#14 0x00007f17bec165db in isc::asiolink::IoServiceThreadPool::threadWork (this=0x5587a22e85b0) at io_service_thread_pool.cc:219
#15 0x00007f17be7e6ed0 in ?? () from /usr/lib/x86_64-linux-gnu/libstdc++.so.6
#16 0x00007f17bf2c2ea7 in start_thread (arg=<optimized out>) at pthread_create.c:477
#17 0x00007f17be4dfaef in clone () at ../sysdeps/unix/sysv/linux/x86_64/clone.S:95
(gdb)
(gdb) Script to reproduce (commented out code needs installed scapy with patch for BLQ support - which I can provide if anyone is interested):
import socket
host_ip, server_port = "2001:db8:1::1000", 9000
# make sure you are using 2001:db8:1::1000 for server and 2001:db8:1::2000 for client
with socket.socket(socket.AF_INET6, socket.SOCK_STREAM) as tcp_client:
tcp_client.settimeout(20)
tcp_client.connect((host_ip, server_port))
d = b"\x0e\x00\x02\x9a\x00,\x00-\x05 \x01\r\xb8\x00\x01\x00\x00\x00\x00\x00\x00\x00\x00 \x00\x00\x01\x00\n\x00\x03\x00\x01\x01\x16 \x17\x1e\x14\x00%\x00\n\x00\x00\x00\x00\n\x00'\x00\x00\x01\x00\x01\x00\n\x00\x03\x00\x01\x01\x16 \x17\x1e\x14"
l = len(d)
msg = l.to_bytes(2, 'big') + d
print(msg)
tcp_client.sendall(msg)
tcp_client.shutdown(socket.SHUT_WR)
received = b''
while True:
buf = tcp_client.recv(1024)
if not buf:
break
received += buf
# from scapy.layers import dhcp6
# def read_dhcp6_msgs(d, msg):
# stop = int.from_bytes(d[:2], "big")
# print(f"received pkt length is {stop}")
# pkt = dhcp6.DHCP6(d[2:stop+2])
# pkt.build()
# msg.append(pkt.copy())
# if len(d[stop:]) > 0:
# msg = read_dhcp6_msgs(d[stop+2:], msg)
# return msg
#
#
# print(len(received))
# print("MSG received in bytes: ", received)
# msgs = read_dhcp6_msgs(received, [])Edited by Wlodzimierz Wencel