unbound-host ends with fatal error "validator/validator.c:392: generate_reques:..."

Tue Oct 25 13:17:38 UTC 2022

Hello,

  if I run "unbound-host -v -dd  -t a google.com -C
/etc/unbound/unbound.conf", it ends with "fatal error:
validator/validator.c:392: generate_request: pointer whitelist
fptr_whitelist_modenv_add_sub( qstate->env->add_sub) failed".

What can I have bad/wrong in my configuration?

I use Unbound from CentOS 7 (unbound-1.6.6-5.el7_8.x86_64).

# unbound-host -v -dd  -t a google.com -C /etc/unbound/unbound.conf
[1666698015] libunbound[5654:0] debug: switching log to stderr
Oct 25 13:40:15 libunbound[5654:0] debug: module config: "validator
iterator"
Oct 25 13:40:15 libunbound[5654:0] notice: init module 0: validator
Oct 25 13:40:15 libunbound[5654:0] debug: wildcard found, processing
/etc/unbound/keys.d/*.inc.key
Oct 25 13:40:15 libunbound[5654:0] debug: trusted-keys-file: no matches for
/etc/unbound/keys.d/*.inc.key
Oct 25 13:40:15 libunbound[5654:0] notice: init module 1: iterator
Oct 25 13:40:15 libunbound[5654:0] debug: target fetch policy for level 0
is 0
Oct 25 13:40:15 libunbound[5654:0] debug: target fetch policy for level 1
is 0
Oct 25 13:40:15 libunbound[5654:0] debug: target fetch policy for level 2
is 0
Oct 25 13:40:15 libunbound[5654:0] debug: target fetch policy for level 3
is 0
Oct 25 13:40:15 libunbound[5654:0] debug: target fetch policy for level 4
is 0
Oct 25 13:40:15 libunbound[5654:0] debug: validator[module 0] operate:
extstate:module_state_initial event:module_event_new
Oct 25 13:40:15 libunbound[5654:0] info: validator operate: query google.com.
A IN
Oct 25 13:40:15 libunbound[5654:0] debug: iterator[module 1] operate:
extstate:module_state_initial event:module_event_pass
Oct 25 13:40:15 libunbound[5654:0] info: resolving google.com. A IN
Oct 25 13:40:15 libunbound[5654:0] info: priming . IN NS
Oct 25 13:40:15 libunbound[5654:0] debug: iterator[module 1] operate:
extstate:module_state_initial event:module_event_pass
Oct 25 13:40:15 libunbound[5654:0] info: iterator operate: query . NS IN
Oct 25 13:40:15 libunbound[5654:0] info: processQueryTargets: . NS IN
Oct 25 13:40:15 libunbound[5654:0] info: sending query: . NS IN
Oct 25 13:40:15 libunbound[5654:0] debug: sending to target: <.>
192.33.4.12#53
Oct 25 13:40:15 libunbound[5654:0] debug: iterator[module 1] operate:
extstate:module_wait_reply event:module_event_reply
Oct 25 13:40:15 libunbound[5654:0] info: iterator operate: query . NS IN
Oct 25 13:40:15 libunbound[5654:0] info: response for . NS IN
Oct 25 13:40:15 libunbound[5654:0] info: reply from <.> 192.33.4.12#53
Oct 25 13:40:15 libunbound[5654:0] info: query response was ANSWER
Oct 25 13:40:15 libunbound[5654:0] debug: validator[module 0] operate:
extstate:module_state_initial event:module_event_moddone
Oct 25 13:40:15 libunbound[5654:0] info: validator operate: query . NS IN
Oct 25 13:40:15 libunbound[5654:0] info: priming successful for . NS IN
Oct 25 13:40:15 libunbound[5654:0] debug: iterator[module 1] operate:
extstate:module_wait_subquery event:module_event_pass
Oct 25 13:40:15 libunbound[5654:0] info: iterator operate: query google.com.
A IN
Oct 25 13:40:15 libunbound[5654:0] info: resolving (init part 2):
google.com. A IN
Oct 25 13:40:15 libunbound[5654:0] info: resolving (init part 3):
google.com. A IN
Oct 25 13:40:15 libunbound[5654:0] info: processQueryTargets: google.com. A
IN
Oct 25 13:40:15 libunbound[5654:0] debug: removing 1 labels
Oct 25 13:40:15 libunbound[5654:0] info: sending query: com. A IN
Oct 25 13:40:15 libunbound[5654:0] debug: sending to target: <.>
193.0.14.129#53
Oct 25 13:40:15 libunbound[5654:0] debug: iterator[module 1] operate:
extstate:module_state_initial event:module_event_pass
Oct 25 13:40:15 libunbound[5654:0] info: iterator operate: query . DNSKEY IN
Oct 25 13:40:15 libunbound[5654:0] info: resolving . DNSKEY IN
Oct 25 13:40:15 libunbound[5654:0] info: resolving (init part 2):  . DNSKEY
IN
Oct 25 13:40:15 libunbound[5654:0] info: resolving (init part 3):  . DNSKEY
IN
Oct 25 13:40:15 libunbound[5654:0] info: processQueryTargets: . DNSKEY IN
Oct 25 13:40:15 libunbound[5654:0] info: sending query: . DNSKEY IN
Oct 25 13:40:15 libunbound[5654:0] debug: sending to target: <.>
192.112.36.4#53
Oct 25 13:40:15 libunbound[5654:0] debug: iterator[module 1] operate:
extstate:module_wait_reply event:module_event_reply
Oct 25 13:40:15 libunbound[5654:0] info: iterator operate: query google.com.
A IN
Oct 25 13:40:15 libunbound[5654:0] info: response for google.com. A IN
Oct 25 13:40:15 libunbound[5654:0] info: reply from <.> 193.0.14.129#53
Oct 25 13:40:15 libunbound[5654:0] info: query response was REFERRAL
Oct 25 13:40:15 libunbound[5654:0] info: processQueryTargets: google.com. A
IN
Oct 25 13:40:15 libunbound[5654:0] info: sending query: google.com. A IN
Oct 25 13:40:15 libunbound[5654:0] debug: sending to target: <com.>
192.33.14.30#53
Oct 25 13:40:15 libunbound[5654:0] debug: iterator[module 1] operate:
extstate:module_state_initial event:module_event_pass
Oct 25 13:40:15 libunbound[5654:0] info: iterator operate: query com.
DNSKEY IN
Oct 25 13:40:15 libunbound[5654:0] info: resolving com. DNSKEY IN
Oct 25 13:40:15 libunbound[5654:0] info: resolving (init part 2):  com.
DNSKEY IN
Oct 25 13:40:15 libunbound[5654:0] info: resolving (init part 3):  com.
DNSKEY IN
Oct 25 13:40:15 libunbound[5654:0] info: processQueryTargets: com. DNSKEY IN
Oct 25 13:40:15 libunbound[5654:0] info: sending query: com. DNSKEY IN
Oct 25 13:40:15 libunbound[5654:0] debug: sending to target: <com.>
192.43.172.30#53
Oct 25 13:40:15 libunbound[5654:0] debug: iterator[module 1] operate:
extstate:module_wait_reply event:module_event_reply
Oct 25 13:40:15 libunbound[5654:0] info: iterator operate: query google.com.
A IN
Oct 25 13:40:15 libunbound[5654:0] info: response for google.com. A IN
Oct 25 13:40:15 libunbound[5654:0] info: reply from <com.> 192.33.14.30#53
Oct 25 13:40:15 libunbound[5654:0] info: query response was REFERRAL
Oct 25 13:40:15 libunbound[5654:0] info: processQueryTargets: google.com. A
IN
Oct 25 13:40:15 libunbound[5654:0] info: sending query: google.com. A IN
Oct 25 13:40:15 libunbound[5654:0] debug: sending to target: <google.com.>
216.239.36.10#53
Oct 25 13:40:15 libunbound[5654:0] debug: iterator[module 1] operate:
extstate:module_wait_reply event:module_event_reply
Oct 25 13:40:15 libunbound[5654:0] info: iterator operate: query . DNSKEY IN
Oct 25 13:40:15 libunbound[5654:0] info: response for . DNSKEY IN
Oct 25 13:40:15 libunbound[5654:0] info: reply from <.> 192.112.36.4#53
Oct 25 13:40:15 libunbound[5654:0] info: query response was ANSWER
Oct 25 13:40:15 libunbound[5654:0] info: finishing processing for . DNSKEY
IN
Oct 25 13:40:15 libunbound[5654:0] debug: validator[module 0] operate:
extstate:module_state_initial event:module_event_moddone
Oct 25 13:40:15 libunbound[5654:0] info: validator operate: query . DNSKEY
IN
Oct 25 13:40:15 libunbound[5654:0] debug: iterator[module 1] operate:
extstate:module_wait_reply event:module_event_reply
Oct 25 13:40:15 libunbound[5654:0] info: iterator operate: query google.com.
A IN
Oct 25 13:40:15 libunbound[5654:0] info: response for google.com. A IN
Oct 25 13:40:15 libunbound[5654:0] info: reply from <google.com.>
216.239.36.10#53
Oct 25 13:40:15 libunbound[5654:0] info: query response was ANSWER
Oct 25 13:40:15 libunbound[5654:0] info: finishing processing for google.com.
A IN
Oct 25 13:40:15 libunbound[5654:0] debug: validator[module 0] operate:
extstate:module_wait_module event:module_event_moddone
Oct 25 13:40:15 libunbound[5654:0] info: validator operate: query google.com.
A IN
Oct 25 13:40:15 libunbound[5654:0] info: prime trust anchor
Oct 25 13:40:15 libunbound[5654:0] fatal error: validator/validator.c:392:
generate_request: pointer whitelist fptr_whitelist_modenv_add_sub(
qstate->env->add_sub) failed

Thanks in advance for any advice.

With best regards,
-- 
Karel Ziegler
 e-mail:    ziegleka at gmail.com
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.nlnetlabs.nl/pipermail/unbound-users/attachments/20221025/51ae2da9/attachment.htm>