Only one request is sent with dig tool, but bind9 processes two same requests and replies with two same responses.
Summary
Use the dig tool to send only one request, but bind receive and processes two same requests and replies with two same responses.
After packets are captured on the network port, only one request message is received and two messages are returned.
(Summarize the bug encountered concisely.)
BIND version used
version: 9.10.0-P1 id:e94d8db1
(Paste the output of named -V
.)
Steps to reproduce
No operation, after running for a while, it's like this. Restart the process. The problem persists.
(How one can reproduce the issue - this is very important.)
What is the current bug behavior?
(What actually happens.)
Use the dig tool to send only one request, but bind processes two same requests and replies with two same responses.
What is the expected correct behavior?
bind only process one request
(What you should see instead.)
Relevant configuration files
(Paste any relevant configuration files - please use code blocks (```)
to format console output. If submitting the contents of your
configuration file in a non-confidential Issue, it is advisable to
obscure key secrets: this can be done automatically by using
named-checkconf -px
.)
Relevant logs and/or screenshots
The following logs are recorded after debugging is enabled.
31-Oct-2021 01:51:54.962 general: socket 0xe4596908: dispatch_recv: event 0xe3f29108 -> task 0xe4597408
31-Oct-2021 01:51:54.963 general: socket 0xe4596788: dispatch_recv: event 0xe3f6a108 -> task 0xe4597388
31-Oct-2021 01:51:54.963 general: socket 0xe4596908: internal_recv: task 0xe4597408 got event 0xe459697c
31-Oct-2021 01:51:54.963 general: socket 0xe4596608: dispatch_recv: event 0xe3fab108 -> task 0xe4597308
31-Oct-2021 01:51:54.963 general: socket 0xe4596788: internal_recv: task 0xe4597388 got event 0xe45967fc
31-Oct-2021 01:51:54.964 general: socket 0xe4596908 127.0.0.1#43060: packet received correctly
31-Oct-2021 01:51:54.964 general: socket 0xe4596488: dispatch_recv: event 0xe3fec108 -> task 0xe4597288
31-Oct-2021 01:51:54.964 general: socket 0xe4596308: dispatch_recv: event 0xe402d108 -> task 0xe4597208
31-Oct-2021 01:51:54.965 general: socket 0xe4596788 127.0.0.1#43060: packet received correctly
31-Oct-2021 01:51:54.965 general: socket 0xe4596908: processing cmsg 0xf6f8d7a0
31-Oct-2021 01:51:54.965 general: socket 0xe4596488: internal_recv: task 0xe4597288 got event 0xe45964fc
31-Oct-2021 01:51:54.966 general: socket 0xe4596608: internal_recv: task 0xe4597308 got event 0xe459667c
31-Oct-2021 01:51:54.966 general: socket 0xe4596188: dispatch_recv: event 0xe406e108 -> task 0xe4597188
31-Oct-2021 01:51:54.966 general: socket 0xe4596788: processing cmsg 0xf6f8d758
31-Oct-2021 01:51:54.967 general: socket 0xe4596788: processing cmsg 0xf6f8d76c
31-Oct-2021 01:51:54.967 general: socket 0xe4596908: processing cmsg 0xf6f8d7b4
31-Oct-2021 01:51:54.967 general: sockmgr 0xf6f77008: watcher got message -3 for socket 532
31-Oct-2021 01:51:54.967 general: socket 0xe4596308: internal_recv: task 0xe4597208 got event 0xe459637c
31-Oct-2021 01:51:54.968 client: client 127.0.0.1#43060: received DSCP 0
31-Oct-2021 01:51:54.968 client: client 127.0.0.1#43060: UDP request
31-Oct-2021 01:51:54.968 client: client 127.0.0.1#43060: received DSCP 0
31-Oct-2021 01:51:54.968 general: sockmgr 0xf6f77008: watcher got message -3 for socket 533
31-Oct-2021 01:51:54.969 general: socket 0xe4596188: internal_recv: task 0xe4597188 got event 0xe45961fc
31-Oct-2021 01:51:54.969 client: client 127.0.0.1#43060: using view '_default'
31-Oct-2021 01:51:54.969 security: client 127.0.0.1#43060: request is not signed
31-Oct-2021 01:51:54.969 security: client 127.0.0.1#43060: recursion available
31-Oct-2021 01:51:54.970 client: client 127.0.0.1#43060: query
31-Oct-2021 01:51:54.970 client: client 0x84f16b0: ns_query_start
31-Oct-2021 01:51:54.970 queries: client 127.0.0.1#43060 (3gnet.test.mncxxx.mccxxx.gprs): query: 3gnet.test.mncxxx.mccxxx.gprs IN A + (127.0.0.1)
31-Oct-2021 01:51:54.970 general: sockmgr 0xf6f77008: watcher got message -3 for socket 531
31-Oct-2021 01:51:54.971 client: client 127.0.0.1#43060: UDP request
31-Oct-2021 01:51:54.971 client: client 127.0.0.1#43060 (3gnet.test.mncxxx.mccxxx.gprs): ns_client_attach: ref = 1
31-Oct-2021 01:51:54.971 client: client 0x84f16b0: query_find
31-Oct-2021 01:51:54.971 client: client 0x84f16b0: query_find: restart
31-Oct-2021 01:51:54.971 general: sockmgr 0xf6f77008: watcher got message -3 for socket 530
31-Oct-2021 01:51:54.972 client: client 127.0.0.1#43060: using view '_default'
31-Oct-2021 01:51:54.972 security: client 127.0.0.1#43060: request is not signed
31-Oct-2021 01:51:54.972 general: sockmgr 0xf6f77008: watcher got message -2 for socket -1
31-Oct-2021 01:51:54.972 security: client 127.0.0.1#43060 (3gnet.test.mncxxx.mccxxx.gprs): query '3gnet.test.mncxxx.mccxxx.gprs/A/IN' approved
31-Oct-2021 01:51:54.972 client: client 0x84f16b0: query_find: db_find
31-Oct-2021 01:51:54.973 client: client 0x84f16b0: query_getnamebuf
31-Oct-2021 01:51:54.973 client: client 0x84f16b0: query_getnamebuf: done
31-Oct-2021 01:51:54.973 client: client 0x84f16b0: query_newname
31-Oct-2021 01:51:54.973 client: client 0x84f16b0: query_newname: done
31-Oct-2021 01:51:54.973 client: client 0x84f16b0: query_newrdataset
31-Oct-2021 01:51:54.973 client: client 0x84f16b0: query_newrdataset: done
31-Oct-2021 01:51:54.974 client: client 0x84f16b0: query_find: resume
31-Oct-2021 01:51:54.974 client: client 0x84f16b0: query_addrrset
31-Oct-2021 01:51:54.974 client: client 0x84f16b0: query_keepname
31-Oct-2021 01:51:54.974 client: client 0x84f16b0: query_addrdataset
31-Oct-2021 01:51:54.974 client: client 0x84f16b0: query_addrdataset: done
31-Oct-2021 01:51:54.974 client: client 0x84f16b0: query_addrrset: done
31-Oct-2021 01:51:54.975 client: client 0x84f16b0: query_find: addauth
31-Oct-2021 01:51:54.975 client: client 0x84f16b0: query_addns
31-Oct-2021 01:51:54.975 client: client 0x84f16b0: query_newrdataset
31-Oct-2021 01:51:54.975 client: client 0x84f16b0: query_newrdataset: done
31-Oct-2021 01:51:54.975 client: client 0x84f16b0: query_addrrset
31-Oct-2021 01:51:54.975 security: client 127.0.0.1#43060: recursion available
31-Oct-2021 01:51:54.976 client: client 127.0.0.1#43060: query
31-Oct-2021 01:51:54.976 client: client 0x84f9200: ns_query_start
31-Oct-2021 01:51:54.976 queries: client 127.0.0.1#43060 (3gnet.test.mncxxx.mccxxx.gprs): query: 3gnet.test.mncxxx.mccxxx.gprs IN A + (127.0.0.1)
31-Oct-2021 01:51:54.976 client: client 0x84f16b0: query_addrdataset
31-Oct-2021 01:51:54.977 client: client 127.0.0.1#43060 (3gnet.test.mncxxx.mccxxx.gprs): ns_client_attach: ref = 1
31-Oct-2021 01:51:54.977 client: client 0x84f9200: query_find
31-Oct-2021 01:51:54.977 client: client 0x84f9200: query_find: restart
31-Oct-2021 01:51:54.977 client: client 0x84f16b0: query_addadditional
31-Oct-2021 01:51:54.977 client: client 0x84f16b0: query_getnamebuf
31-Oct-2021 01:51:54.977 client: client 0x84f16b0: query_getnamebuf: done
31-Oct-2021 01:51:54.978 client: client 0x84f16b0: query_newname
31-Oct-2021 01:51:54.978 client: client 0x84f16b0: query_newname: done
31-Oct-2021 01:51:54.978 client: client 0x84f16b0: query_newrdataset
31-Oct-2021 01:51:54.978 client: client 0x84f16b0: query_newrdataset: done
31-Oct-2021 01:51:54.978 client: client 0x84f16b0: query_addadditional: db_find
31-Oct-2021 01:51:54.979 client: client 0x84f16b0: query_keepname
31-Oct-2021 01:51:54.979 security: client 127.0.0.1#43060 (3gnet.test.mncxxx.mccxxx.gprs): query '3gnet.test.mncxxx.mccxxx.gprs/A/IN' approved
(Paste any relevant logs - please use code blocks (```) to format console output, logs, and code, as it's very hard to read otherwise.)
Possible fixes
(If you can, link to the line of code that might be responsible for the problem.)