Unbound randomly fails to resolve names

Hi,

After resolving my performance issues in the thread :

“Unbound can be made unresponsive when using DoT" in the June archives.

I now need to understand why Unbound randomly fails to resolve names and causes various processes on my system to work incorrectly.

I hope the unbound users list allows attachments I have tried to add a zip file GetRootHints.zip which contains all the data to cause the issue.

It contains a short PowerShell script just to obtain named.root from:

https://www.internic.net/domain/named.root

Examples of what happens when it fails and also when it works.

The unbound log file for both working and failing – verbosity 4

I have also included the Unbound configuration file details

So if anyone can advise why things are going wrong and how to correct them if indeed I can by changing the configuration.

It would also be useful to know if this example fails on other systems.

The zip file can also be found on MSOneDrive here:

https://1drv.ms/u/s!As73rPtzISrUzQ_Cgu81TC4xZRsE?e=BMHmDN

Thanks

Ray

(attachments)

GetRootHintsData.zip (71.4 KB)

Hmmm,

Is no one else seeing this issue?

I have seen no replies from nlnetlabs or anyone else.

There must be a reason why things are failing?

Puzzle by the lack of response.

Ray

Is no one else seeing this issue?
I have seen no replies from nlnetlabs or anyone else.

...

*From:*RayG <rgsub1@btinternet.com>
*Sent:* 01 July 2020 15:31
*To:* unbound-users@nlnetlabs.nl
*Subject:* Unbound randomly fails to resolve names

...

It contains a short PowerShell script just to obtain named.root from:
https://www.internic.net/domain/named.root

Examples of what happens when it fails and also when it works.

...

The zip file can also be found on MSOneDrive here:
https://1drv.ms/u/s!As73rPtzISrUzQ_Cgu81TC4xZRsE?e=BMHmDN

Attachments, free file drop sites, and unsigned compressed files may make some people nervous for good reason. If what you wish to share is plain text, then just inline it to the email. You may want to use some kind of markdown marker to denote your code and log blocks (github: ``` or Jira: {code} for examples).

OK here it is inline apologies for the length:

Running on:
Windows 10/64 (B18363.900-V1909) on:
Intel Core i7 4930K @ 3.40GHz Ivy Bridge-E 22nm with 32GB Memory,

To show the problem I am seeing here is a little PowerShell script just to
get the
Root Hints file from www.internic.net (between the %%% signs):

%%%%%
# URI to obtain the root hints file
Set-Variable -Name URI_RH -Value
'https://www.internic.net/domain/named.root
# Set Date Time format
Set-Variable -Name DTFormat -Value 'dd/MM/yyyy HH:mm:ss'
$Global:Error.Clear()
Try
{
  # Obtain the Root Hints list of servers
  Get-Date -Format $DTFormat
  $RHDataIn = (Invoke-WebRequest -Uri ('{0}' -f $URI_RH)).Content
  Get-Date -Format $DTFormat
}
Catch
{
  Get-Date -Format $DTFormat
  Write-Warning -Message ('Unable to obtain root hints from: [{0}]' -f
$URI_RH)
  $Global:Error
}
# Uncomment the line below to see the data returned
#$RHDataIn
%%%%%

When run, if it fails you see the following output:

PS C:\> S:\GetRootHintsData.ps1
01/07/2020 14:36:33
01/07/2020 14:36:35
WARNING: Unable to obtain root hints from:
[https://www.internic.net/domain/named.root\]
Invoke-WebRequest : The remote name could not be resolved:
'www.internic.net'
At S:\GetRootHintsData.ps1:10 char:16
+ $RHDataIn = (Invoke-WebRequest -Uri ('{0}' -f $URI_RH)).Content
+ ~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~~
    + CategoryInfo : InvalidOperation:
(System.Net.HttpWebRequest:HttpWebRequest) [Invoke-WebRequest], WebExc
   eption
    + FullyQualifiedErrorId :
WebCmdletWebResponseException,Microsoft.PowerShell.Commands.InvokeWebRequest
Command

PS C:\>

This is the output when it works:

PS C:\> S:\GetRootHintsData.ps1
01/07/2020 14:42:31
01/07/2020 14:42:32
PS C:\>

The times can be used to select the Unbound log entries so you know when it
started
and when it finished with a little trial and error you can hit a quiet time
when
nothing else DNS is going on which is what I hope I have done with the two
examples below.

Search for the: ==== signs to skip to the examples below.

This is the configuration file (with a couple of omissions) between the ----
signs

I did as requested and sent things in plain text but I have still had no
response from anyone?

This issue is causing me issues every day and I would just like to get it
resolved one way or another.

Any help or suggestions as to why Unbound is failing randomly would be of
help.

I should add that not every failure is caught by the event log. At least
this shows that the failures are for a wide range of addresses:

Level Date and Time Source Event ID Task Category
Warning 16/07/2020 15:48:44 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name enews.synology.com timed out after none
of the configured DNS servers responded.
Warning 15/07/2020 19:49:34 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name geo.prod.do.dsp.mp.microsoft.com timed
out after none of the configured DNS servers responded.
Warning 15/07/2020 19:49:30 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name edge.skype.com timed out after none of
the configured DNS servers responded.
Warning 15/07/2020 15:07:55 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name neofusgate.samsung.com.cn timed out
after none of the configured DNS servers responded.
Warning 15/07/2020 13:53:11 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name cdatatec.com timed out after none of
the configured DNS servers responded.
Warning 14/07/2020 15:10:43 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name conf-btpc-prod.uk-uxb.synchronoss.com
timed out after none of the configured DNS servers responded.
Warning 13/07/2020 15:29:50 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name conf-btpc-prod.uk-uxb.synchronoss.com
timed out after none of the configured DNS servers responded.
Warning 12/07/2020 15:14:08 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name www.internic.net timed out after none
of the configured DNS servers responded.
Warning 11/07/2020 15:10:51 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name 261vfb.tdum.alibaba.com timed out after
none of the configured DNS servers responded.
Warning 11/07/2020 13:44:11 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name kdp.amazon.com timed out after none of
the configured DNS servers responded.
Warning 10/07/2020 22:50:57 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name trouter-weu-b.trouter.skype.com timed
out after none of the configured DNS servers responded.
Warning 10/07/2020 17:06:53 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name
a3607b2817b5e934ba204692e7a98f369.profile.maa50-c2.cloudfront.net timed out
after none of the configured DNS servers responded.
Warning 09/07/2020 14:22:24 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name manage.devcenter.microsoft.com timed
out after none of the configured DNS servers responded.
Warning 07/07/2020 14:23:34 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name disc601.prod.do.dsp.mp.microsoft.com
timed out after none of the configured DNS servers responded.
Warning 06/07/2020 15:08:47 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name
azwus1-client-s.gateway.messenger.live.com timed out after none of the
configured DNS servers responded.
Warning 06/07/2020 15:08:42 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name trouter-weu-b.trouter.skype.com timed
out after none of the configured DNS servers responded.
Warning 06/07/2020 12:50:17 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name www.internic.net timed out after none
of the configured DNS servers responded.
Warning 06/07/2020 11:41:40 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name
cid-d42a2173fbacf7ce.users.storage.live.com timed out after none of the
configured DNS servers responded.
Warning 05/07/2020 17:29:29 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name www.internic.net timed out after none
of the configured DNS servers responded.
Warning 04/07/2020 16:46:25 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name mobile.pipe.aria.microsoft.com timed
out after none of the configured DNS servers responded.
Warning 04/07/2020 16:46:19 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name trouter-weu-a.trouter.skype.com timed
out after none of the configured DNS servers responded.
Warning 04/07/2020 13:44:43 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name mozilla.cloudflare-dns.com timed out
after none of the configured DNS servers responded.
Warning 04/07/2020 13:44:40 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name trouter-weu-a.trouter.skype.com timed
out after none of the configured DNS servers responded.
Warning 03/07/2020 15:18:46 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name www.internic.net timed out after none
of the configured DNS servers responded.
Warning 02/07/2020 14:31:27 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name
azwus1-client-s.gateway.messenger.live.com timed out after none of the
configured DNS servers responded.
Warning 02/07/2020 13:21:17 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name mobile.pipe.aria.microsoft.com timed
out after none of the configured DNS servers responded.
Warning 01/07/2020 16:18:19 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name www.internic.net timed out after none
of the configured DNS servers responded.
Warning 01/07/2020 13:36:01 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name people.skype.com timed out after none
of the configured DNS servers responded.
Warning 01/07/2020 13:35:56 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name trouter-weu-a.trouter.skype.com timed
out after none of the configured DNS servers responded.
Warning 01/07/2020 11:00:25 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name
cid-d42a2173fbacf7ce.users.storage.live.com timed out after none of the
configured DNS servers responded.
Warning 30/06/2020 13:54:11 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name bulkrenameutility.co.uk timed out after
none of the configured DNS servers responded.
Warning 29/06/2020 16:19:25 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name www.internic.net timed out after none
of the configured DNS servers responded.
Warning 29/06/2020 15:31:27 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name trouter-weu-a.trouter.skype.com timed
out after none of the configured DNS servers responded.
Warning 29/06/2020 12:32:12 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name trouter-weu-a.trouter.skype.com timed
out after none of the configured DNS servers responded.
Warning 29/06/2020 11:52:49 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name toc.cryptobook.us. timed out after none
of the configured DNS servers responded.
Warning 28/06/2020 17:51:44 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name wdcp.microsoft.com timed out after none
of the configured DNS servers responded.
Warning 28/06/2020 16:09:03 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name 5.176.45.69.in-addr.arpa. timed out
after none of the configured DNS servers responded.
Warning 28/06/2020 13:50:50 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name bulkrenameutility.co.uk timed out after
none of the configured DNS servers responded.
Warning 27/06/2020 14:33:06 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name dclocator.btcloud.bt.com timed out
after none of the configured DNS servers responded.
Warning 27/06/2020 13:24:55 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name trouter-weu-b.trouter.skype.com timed
out after none of the configured DNS servers responded.
Warning 27/06/2020 11:47:17 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name array501.prod.do.dsp.mp.microsoft.com
timed out after none of the configured DNS servers responded.
Warning 27/06/2020 11:47:14 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name trouter-weu-b.trouter.skype.com timed
out after none of the configured DNS servers responded.
Warning 27/06/2020 10:32:25 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name config.edge.skype.com timed out after
none of the configured DNS servers responded.
Warning 26/06/2020 18:16:41 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name trouter-weu-b.trouter.skype.com timed
out after none of the configured DNS servers responded.
Warning 26/06/2020 18:16:39 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name trouter-weu-b.trouter.skype.com timed
out after none of the configured DNS servers responded.
Warning 26/06/2020 17:59:54 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name d.dropbox.com timed out after none of
the configured DNS servers responded.
Warning 26/06/2020 16:50:03 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name pvoutput.org timed out after none of
the configured DNS servers responded.
Warning 26/06/2020 15:39:04 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name imap.csi.com timed out after none of
the configured DNS servers responded.
Warning 26/06/2020 15:17:50 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name conf-btpc-prod.uk-uxb.synchronoss.com
timed out after none of the configured DNS servers responded.
Warning 26/06/2020 13:52:45 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name trouter-weu-b.trouter.skype.com timed
out after none of the configured DNS servers responded.
Warning 26/06/2020 12:36:29 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name
azwus1-client-s.gateway.messenger.live.com timed out after none of the
configured DNS servers responded.
Warning 26/06/2020 12:36:26 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name trouter-weu-b.trouter.skype.com timed
out after none of the configured DNS servers responded.
Warning 26/06/2020 11:22:18 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name cp501.prod.do.dsp.mp.microsoft.com
timed out after none of the configured DNS servers responded.
Warning 24/06/2020 16:22:52 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name sqrl.ver.grc.com timed out after none
of the configured DNS servers responded.
Warning 24/06/2020 15:22:33 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name euaz.tr.skype.com timed out after none
of the configured DNS servers responded.
Warning 23/06/2020 16:20:30 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name www.bing.com timed out after none of
the configured DNS servers responded.
Warning 23/06/2020 15:20:54 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name e0yju1d0a3v2j.dns.grc.com timed out
after none of the configured DNS servers responded.
Warning 22/06/2020 17:28:08 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name
b2b512fe-07d3-4471-91cf-1e74b7e840f1.is-cf.cloudflareresolve.com timed out
after none of the configured DNS servers responded.
Warning 21/06/2020 15:07:23 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name
1.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.0.4.c.d.0.1.0.0.2.ip6.arpa.
timed out after none of the configured DNS servers responded.
Warning 21/06/2020 14:26:11 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name detectportal.firefox.com timed out
after none of the configured DNS servers responded.
Warning 21/06/2020 13:51:56 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name api.sec-smartswitch.com timed out after
none of the configured DNS servers responded.
Warning 21/06/2020 13:26:33 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name wpad timed out after none of the
configured DNS servers responded.
Warning 20/06/2020 18:20:24 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name dnsbench.a1wkth2ehsvda.ver.grc.com
timed out after none of the configured DNS servers responded.
Warning 20/06/2020 15:55:38 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name nstoro.com timed out after none of the
configured DNS servers responded.
Warning 20/06/2020 14:50:21 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name
7eddb2eb-8926-4528-81c7-700465fc5fbd.is-dot.cloudflareresolve.com timed out
after none of the configured DNS servers responded.
Warning 19/06/2020 15:07:12 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name conf-btpc-prod.uk-uxb.synchronoss.com
timed out after none of the configured DNS servers responded.
Warning 18/06/2020 14:23:08 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name conf-btpc-prod.uk-uxb.synchronoss.com
timed out after none of the configured DNS servers responded.
Warning 15/06/2020 15:01:33 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name
msedge.b.tlu.dl.delivery.mp.microsoft.com timed out after none of the
configured DNS servers responded.
Warning 15/06/2020 14:13:14 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name ctldl.windowsupdate.com timed out after
none of the configured DNS servers responded.
Warning 15/06/2020 13:13:15 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name ctldl.windowsupdate.com timed out after
none of the configured DNS servers responded.
Warning 15/06/2020 13:11:45 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name geo.prod.do.dsp.mp.microsoft.com timed
out after none of the configured DNS servers responded.
Warning 14/06/2020 16:23:54 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name www.internic.net timed out after none
of the configured DNS servers responded.
Warning 12/06/2020 15:10:51 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name www.eff.org timed out after none of the
configured DNS servers responded.
Warning 12/06/2020 13:53:49 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name dclocator.btcloud.bt.com timed out
after none of the configured DNS servers responded.
Warning 12/06/2020 11:38:22 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name sqrl.ver.grc.com timed out after none
of the configured DNS servers responded.
Warning 11/06/2020 16:10:54 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name edge.activity.windows.com timed out
after none of the configured DNS servers responded.
Warning 10/06/2020 19:40:05 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name www.bulkrenameutility.co.uk timed out
after none of the configured DNS servers responded.
Warning 10/06/2020 16:55:14 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name bogus.d1a14n3.rootcanary.net timed out
after none of the configured DNS servers responded.
Warning 10/06/2020 15:21:51 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name api2.cricut.com timed out after none of
the configured DNS servers responded.
Warning 10/06/2020 15:11:51 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name wpad timed out after none of the
configured DNS servers responded.
Warning 10/06/2020 13:22:58 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name nstoro.com timed out after none of the
configured DNS servers responded.
Warning 09/06/2020 15:44:27 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name wpad timed out after none of the
configured DNS servers responded.
Warning 09/06/2020 15:09:34 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name www.internic.net timed out after none
of the configured DNS servers responded.
Warning 08/06/2020 22:47:26 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name wpad timed out after none of the
configured DNS servers responded.
Warning 08/06/2020 16:04:27 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name liajcktjrk timed out after none of the
configured DNS servers responded.
Warning 08/06/2020 16:04:22 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name wpad timed out after none of the
configured DNS servers responded.
Warning 07/06/2020 23:13:23 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name wpad timed out after none of the
configured DNS servers responded.
Warning 07/06/2020 13:47:02 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name conf-btpc-prod.uk-uxb.synchronoss.com
timed out after none of the configured DNS servers responded.
Warning 06/06/2020 14:38:03 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name my.bt.com timed out after none of the
configured DNS servers responded.
Warning 03/06/2020 16:32:15 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name cdn.bootcss.com timed out after none of
the configured DNS servers responded.
Warning 03/06/2020 15:53:38 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name download.lsoft.net timed out after none
of the configured DNS servers responded.
Warning 02/06/2020 15:04:23 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name wpad timed out after none of the
configured DNS servers responded.
Warning 01/06/2020 16:02:18 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name
discourse-cloud-file-uploads.s3.dualstack.us-west-2.amazonaws.com timed out
after none of the configured DNS servers responded.
Warning 30/05/2020 17:23:22 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name www.easeus.com timed out after none of
the configured DNS servers responded.
Warning 29/05/2020 18:18:44 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name qictuua timed out after none of the
configured DNS servers responded.
Warning 29/05/2020 15:11:09 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name conf-btpc-prod.uk-uxb.synchronoss.com
timed out after none of the configured DNS servers responded.
Warning 29/05/2020 14:19:26 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name wpad timed out after none of the
configured DNS servers responded.
Warning 27/05/2020 17:15:32 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name btcloud.bt.com timed out after none of
the configured DNS servers responded.
Warning 24/05/2020 15:27:36 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name atqeidgeurm timed out after none of the
configured DNS servers responded.
Warning 23/05/2020 15:32:58 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name conf-btpc-prod.uk-uxb.synchronoss.com
timed out after none of the configured DNS servers responded.
Warning 23/05/2020 15:31:03 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name euaz.tr.azsc.skype.com timed out after
none of the configured DNS servers responded.
Warning 23/05/2020 15:11:18 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name wdcp.microsoft.com timed out after none
of the configured DNS servers responded.
Warning 23/05/2020 14:59:02 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name romeccs.microsoft.com timed out after
none of the configured DNS servers responded.
Warning 23/05/2020 14:58:36 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name euaz.tr.azsc.skype.com timed out after
none of the configured DNS servers responded.
Warning 22/05/2020 16:46:17 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name www.internic.net timed out after none
of the configured DNS servers responded.
Warning 22/05/2020 16:28:35 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name news.grc.com timed out after none of
the configured DNS servers responded.
Warning 22/05/2020 14:27:09 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name disc501.prod.do.dsp.mp.microsoft.com
timed out after none of the configured DNS servers responded.
Warning 22/05/2020 12:46:23 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name api3.cc.skype.com timed out after none
of the configured DNS servers responded.
Warning 21/05/2020 16:32:53 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name
onedriveclucprodbn20027.blob.core.windows.net timed out after none of the
configured DNS servers responded.
Warning 21/05/2020 15:55:27 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name smartscreen-prod.microsoft.com timed
out after none of the configured DNS servers responded.
Warning 21/05/2020 14:34:40 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name www.synology.com timed out after none
of the configured DNS servers responded.
Warning 20/05/2020 14:46:29 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name wwwimages2.adobe.com timed out after
none of the configured DNS servers responded.
Warning 20/05/2020 13:57:49 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name www.dropboxstatic.com timed out after
none of the configured DNS servers responded.
Warning 20/05/2020 13:24:46 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name loki.delve.office.com timed out after
none of the configured DNS servers responded.
Warning 20/05/2020 12:40:33 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name conf-btpc-prod.uk-uxb.synchronoss.com
timed out after none of the configured DNS servers responded.
Warning 20/05/2020 12:39:53 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name get.adobe.com timed out after none of
the configured DNS servers responded.
Warning 19/05/2020 15:21:50 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name aefd.nelreports.net timed out after
none of the configured DNS servers responded.
Warning 19/05/2020 13:30:51 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name dmd.metaservices.microsoft.com timed
out after none of the configured DNS servers responded.
Warning 19/05/2020 13:00:10 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name euaz.tr.azsc.skype.com timed out after
none of the configured DNS servers responded.
Warning 18/05/2020 13:15:11 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name login.microsoftonline.com timed out
after none of the configured DNS servers responded.
Warning 16/05/2020 15:32:20 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name wpad timed out after none of the
configured DNS servers responded.
Warning 15/05/2020 15:03:25 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name dmd.metaservices.microsoft.com timed
out after none of the configured DNS servers responded.
Warning 15/05/2020 14:38:59 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name euaz.tr.skype.com timed out after none
of the configured DNS servers responded.
Warning 14/05/2020 14:21:33 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name conf-btpc-prod.uk-uxb.synchronoss.com
timed out after none of the configured DNS servers responded.
Warning 13/05/2020 16:43:04 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name disc601.prod.do.dsp.mp.microsoft.com
timed out after none of the configured DNS servers responded.
Warning 13/05/2020 16:18:25 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name euaz.tr.skype.com timed out after none
of the configured DNS servers responded.
Warning 12/05/2020 14:52:10 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name conf-btpc-prod.uk-uxb.synchronoss.com
timed out after none of the configured DNS servers responded.
Warning 11/05/2020 17:09:45 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name dclocator.btcloud.bt.com timed out
after none of the configured DNS servers responded.
Warning 09/05/2020 09:35:38 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name conf-btpc-prod.uk-uxb.synchronoss.com
timed out after none of the configured DNS servers responded.
Warning 08/05/2020 14:23:02 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name www.synology.com timed out after none
of the configured DNS servers responded.
Warning 06/05/2020 12:58:28 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name media.mimslearning.co.uk timed out
after none of the configured DNS servers responded.
Warning 05/05/2020 10:35:09 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name oloobe.officeapps.live.com timed out
after none of the configured DNS servers responded.
Warning 04/05/2020 12:17:46 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name conf-btpc-prod.uk-uxb.synchronoss.com
timed out after none of the configured DNS servers responded.
Warning 02/05/2020 15:31:14 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name conf-btpc-prod.uk-uxb.synchronoss.com
timed out after none of the configured DNS servers responded.
Warning 02/05/2020 13:00:03 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name conf-btpc-prod.uk-uxb.synchronoss.com
timed out after none of the configured DNS servers responded.
Warning 01/05/2020 18:00:08 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name euaz.tr.skype.com timed out after none
of the configured DNS servers responded.
Warning 01/05/2020 15:22:53 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name wdcp.microsoft.com timed out after none
of the configured DNS servers responded.
Warning 01/05/2020 13:34:45 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name conf-btpc-prod.uk-uxb.synchronoss.com
timed out after none of the configured DNS servers responded.
Warning 30/04/2020 20:30:03 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name nav.smartscreen.microsoft.com timed out
after none of the configured DNS servers responded.
Warning 30/04/2020 20:17:17 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name conf-btpc-prod.uk-uxb.synchronoss.com
timed out after none of the configured DNS servers responded.
Warning 30/04/2020 17:39:41 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name sqrl.ver.grc.com timed out after none
of the configured DNS servers responded.
Warning 30/04/2020 16:20:49 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name neofusgate.samsung.com.cn timed out
after none of the configured DNS servers responded.
Warning 29/04/2020 17:39:05 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name sfgbr.loki.delve.office.com timed out
after none of the configured DNS servers responded.
Warning 27/04/2020 14:47:42 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name
cid-d42a2173fbacf7ce.users.storage.live.com timed out after none of the
configured DNS servers responded.
Warning 26/04/2020 17:34:11 Microsoft-Windows-DNS-Client 1014
(1014) Name resolution for the name use.typekit.net timed out after none of
the configured DNS servers responded.

Zitat von RayG via Unbound-users <unbound-users@lists.nlnetlabs.nl>:

I did as requested and sent things in plain text but I have still had no
response from anyone?

This issue is causing me issues every day and I would just like to get it
resolved one way or another.

Any help or suggestions as to why Unbound is failing randomly would be of
help.

I should add that not every failure is caught by the event log. At least
this shows that the failures are for a wide range of addresses:

Me too. Maybe...

As of now (23:34) two unbound instances fail to resolve cloudflare.net NS and anything using it as DNS service.

A "dig @127.0.0.1 cloudflare.net NS" lead to no servers could be reached, "dig @h.gtld-servers.net cloudflare.net NS" is working as expected. Nothing in the unboud log, and some minutes later all is working as expected again? In one case it is Ubuntu 16.04 with unbound 1.5.8, the other one unbound 1.6.7 both validating DNSSEC.

Regards

Andi

As of now (23:34) two unbound instances fail to resolve cloudflare.net NS
and anything using it as DNS service.

A "dig @127.0.0.1 cloudflare.net NS" lead to no servers could be reached,
"dig @h.gtld-servers.net cloudflare.net NS" is working as expected. Nothing
in the unboud log, and some minutes later all is working as expected again?
In one case it is Ubuntu 16.04 with unbound 1.5.8, the other one unbound
1.6.7 both validating DNSSEC.

That was Cloudflare who proved that you can get extremely good
reliability if you have distributed services with a shared control
plane. Or maybe not.

Hi Ray, Andi,

I see from Ray's log that use-caps-for-id: is enabled.
I also see that the forwarding resolvers used seem to have an issue with
0x20 replies (use-caps-for-id related).

For example:
When unbound asks for an.ExaMple.domAin.NeT and the record is not cached
in the forwarder, the answer will contain the correct case.
Afterwards, when the answer is cached, the wrong casing (always
lowercase) will be used, and until the TTL expires I assume. This
results in a mismatch between query and reply if use-caps-for-id is used.

Unbound's fallback may or may not help at that time. From your log I see
that the fallback does not help (returns SERVFAIL after some further
tries) and consecutive queries try without 0x20.

I will try to reach the people involved but for now turning off
use-caps-for-id should help.

Let us know how it goes.

Best regards,
-- George

Hi George,

I'm also suffering from this strange fail -- since years.
It's become noticeable better with 1.9.x though.

Ususally, if the result is not in the cache, it takes a minute or two
to finally get the resolution. Not sure if it's become better with the
update or with changing dns servers. It happens randomly, even with
common domains, and not too often.

My unbound.conf also uses "use-caps-for-id: yes", because of this guide:
https://calomel.org/unbound_dns.html

I'm following with interest if this was the cause! :wink:

Best Regards
Oliver

Hi George, Oliver, Andi,

@George: Thanks for your reply.

I have made the adjustment we will see how it goes.

But as Oliver Psotta says at https://calomel.org/unbound_dns.html there are good reasons for having it enabled.

Also on the page: https://www.grc.com/dns/dns.htm there is a "Spoofabity" test which also suggests having mixed case is good.

There are also the TCP Errors e.g.:
21/07/2020 11:15:01 C:\Program Files\Unbound\unbound.exe[16308:0] debug: tcp error for address 1.0.0.1 port 853
These are unexplained so far as are some of the other entries like:
21/07/2020 10:41:40 C:\Program Files\Unbound\unbound.exe[16308:0] debug: request E.ROOT-SERVERS.NET. has exceeded the maximum number of glue fetches 65
21/07/2020 10:41:40 C:\Program Files\Unbound\unbound.exe[16308:0] debug: return error response SERVFAIL
And
21/07/2020 10:41:40 C:\Program Files\Unbound\unbound.exe[16308:0] debug: request has exceeded the maximum number of nxdomain nameserver lookups with 13
21/07/2020 10:41:40 C:\Program Files\Unbound\unbound.exe[16308:0] debug: return error response SERVFAIL

All of which are still occurring, should they be happening?

Also I have been able to look back at some of my backup images these were all running the same way as currently and the event log messages like:

Level Date and Time Source Event ID Task Category
Warning 16/07/2020 15:48:44 Microsoft-Windows-DNS-Client 1014 (1014) Name resolution for the name enews.synology.com timed out after none of the configured DNS servers responded.

Are present.

These started occurring after the release of V1.9.4. The event log on that backup image (which I am able to run as a virtual machine) did not contain any of the above errors.
So V1.9.4 was OK

Unfortunately between the above VM and the next one I can run there was V1.9.5 and the download file for that was dated at 19/11/2019
V1.9.6. download date was 12/12/2019 I can say that the above type of errors started appearing just after V1.9.5 was downloaded. I normally install the new version on the same day that I download it. So something happened somewhere between V1.9.4 and V1.9.5 and has been the same ever since.

I hope that helps.

Thanks for any further information/comments

Ray

Hi Ray,

Hi George, Oliver, Andi,

@George: Thanks for your reply.

I have made the adjustment we will see how it goes.

But as Oliver Psotta says at https://calomel.org/unbound_dns.html there are good reasons for having it enabled.

Also on the page: https://www.grc.com/dns/dns.htm there is a "Spoofabity" test which also suggests having mixed case is good.

Having the option enabled is good as long as the other side supports it.
This is not the case for you, at least for now.

If you want to keep it enabled you can enrich your forwarders
configuration with other public DoT resolvers.
You can find more information at
https://dnsprivacy.org/wiki/display/DP/DNS+Privacy+Public+Resolvers#DNSPrivacyPublicResolvers-DNS-over-TLS(DoT).

There are also the TCP Errors e.g.:
21/07/2020 11:15:01 C:\Program Files\Unbound\unbound.exe[16308:0] debug: tcp error for address 1.0.0.1 port 853

Nothing wrong here, seems like a tcp error to that IP and port. Unbound
couldn't make the connection (maybe network routing problems,
unavailability from the other side or the local system) and it should go
on to try the next available server.

These are unexplained so far as are some of the other entries like:
21/07/2020 10:41:40 C:\Program Files\Unbound\unbound.exe[16308:0] debug: request E.ROOT-SERVERS.NET. has exceeded the maximum number of glue fetches 65
21/07/2020 10:41:40 C:\Program Files\Unbound\unbound.exe[16308:0] debug: return error response SERVFAIL
And
21/07/2020 10:41:40 C:\Program Files\Unbound\unbound.exe[16308:0] debug: request has exceeded the maximum number of nxdomain nameserver lookups with 13
21/07/2020 10:41:40 C:\Program Files\Unbound\unbound.exe[16308:0] debug: return error response SERVFAIL

All of which are still occurring, should they be happening?

Both of the above are because resolution has exceeded a set of limits
and the query is considered as hitting a dead end from unbound's point
of view (there seems to be no available servers that can provide an answer).
Unbound stops resolution and returns SERVFAIL to the client(s).

As you are forwarding to a limited set of resolvers (in contrast with
reaching the different authoritative nameservers during normal
resolution), those kind of limits could be reached easier/faster if
there are communication issues as the upstream is the same and sole
responsible server for all the delegation points.

Also I have been able to look back at some of my backup images these were all running the same way as currently and the event log messages like:

Level Date and Time Source Event ID Task Category
Warning 16/07/2020 15:48:44 Microsoft-Windows-DNS-Client 1014 (1014) Name resolution for the name enews.synology.com timed out after none of the configured DNS servers responded.

Are present.

These started occurring after the release of V1.9.4. The event log on that backup image (which I am able to run as a virtual machine) did not contain any of the above errors.
So V1.9.4 was OK

Unfortunately between the above VM and the next one I can run there was V1.9.5 and the download file for that was dated at 19/11/2019
V1.9.6. download date was 12/12/2019 I can say that the above type of errors started appearing just after V1.9.5 was downloaded. I normally install the new version on the same day that I download it. So something happened somewhere between V1.9.4 and V1.9.5 and has been the same ever since.

I believe the difference in behavior is only a coincidence for unbound.
1.9.5 was a CVE release that was solving a security vulnerability in the
ipsecmod module. It had nothing to do with upstream connections, tcp
connections, or DoT and if unbound is not compiled with the ipsecmod,
the code should be identical to the 1.9.4 version.

Best regards,
-- George

Hi George,

OK thanks for the confirmation of the other issues I have seen.

With respect to the use-caps-for-id when you say "as long as the other side supports it" CloudFlare does support it because it works most of the time.

You also said:

"When unbound asks for an.ExaMple.domAin.NeT and the record is not cached in the forwarder, the answer will contain the correct case.
Afterwards, when the answer is cached, the wrong casing (always lowercase) will be used, and until the TTL expires I assume.
This results in a mismatch between query and reply if use-caps-for-id is used."

So am I right in the understanding that the initial query goes to CloudFlare and CloudFlare has to go off and resolve the name itself (its not in the cache) then the correct case is preserved. Now CloudFlare has the name stored in its cache. A subsequent query for the same DNS name is then requested where the casing is randomly different and then a case insensitive match is found in CloudFlare's cache and the cache entry casing is returned rather than the query with the different casing that the client just requested. Then you get the error. This sounds like a bug to me and something CloudFlare should be aware of and fix? Is that what you were referring to when you said "I will try to reach the people involved"?

For the "tcp error" it would be nice to have a bit more information in the log as to what actually failed e.g. connection refused, timed out etc. It would help to show it was the other end not Unbound.

I was using just CloudFlare to make sure that when I submitted the logs etc. that I had a repeatable, consistent issue to look at. And yes since setting 'use-caps-for-id' to 'no' there have been no issues.

I have now set 'use-caps-for-id' to 'yes' and changed the list of forwarders to see what happens:

forward-zone: # MyForwardZones.conf
  name: "."
  forward-tls-upstream: yes
  forward-first: yes
  # Cloudflare DNS
  forward-addr: 1.1.1.1@853#cloudflare-dns.com
  forward-addr: 1.0.0.1@853#cloudflare-dns.com
  forward-addr: 2606:4700:4700::1111@853#cloudflare-dns.com
  forward-addr: 2606:4700:4700::1001@853#cloudflare-dns.com
  # Quad9
  forward-addr: 2620:fe::fe@853#dns.quad9.net
  forward-addr: 9.9.9.9@853#dns.quad9.net
  #forward-addr: 2620:fe::9@853#dns.quad9.net
  #forward-addr: 149.112.112.112@853#dns.quad9.net
  # Google
  forward-addr: 8.8.8.8@853#Dns.google
  forward-addr: 8.8.4.4@853#Dns.google
  forward-addr: 2001:4860:4860::8888@853#Dns.google
  forward-addr: 2001:4860:4860::8844@853#Dns.google
  # DNS Privacy
  forward-addr: 94.130.110.185@853#ns1.dnsprivacy.at
  forward-addr: 94.130.110.178@853#ns2.dnsprivacy.at

Thanks again for the information it has been useful to me and I suspect others.

Ray

Hi George,

Following on from my last reply, having set up a number of forwarders and turned on the (use-caps-for-id: yes) I had this in the log file for sqrl.ver.grc.com (see below)

I think we see a "Capsforid: starting fallback" 3 times and then "Capsforid: reply is equal. go to next fallback" 4 times.

I assume that the "Capsforid: starting fallback" Means that there was a case mismatch in the reply?

But then we get a "Capsforid: reply is equal. go to next fallback" where the "reply is equal" which suggests that the case matches?

So why does this all seem end up with this, were we have gone from 4 CloudFlare servers to 12 servers from different companies and yet we still get " exceeded the maximum number of glue fetches 17 to a single delegation point".

23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: request sqrl.ver.grc.com. has exceeded the maximum number of glue fetches 17 to a single delegation point
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: return error response SERVFAIL
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query sqrl.ver.grc.com. A IN
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] error: SERVFAIL <sqrl.ver.grc.com. A IN>: could not fetch nameservers for 0x20 fallback

And then immediately after seemingly a set of queries that succeed.

Apologies for all the questions but the logs (to me) seem to show that Unbound, when it gets a good reply does not cancel/ignore/discard all the outstanding queries and return the good result.

I do admit as I have said before that I understand what is happening but not the finer details of why. (I am not an expert by any means just trying to understand)

Below are all the lines from the log file (none are edited out) from the first occurrence in the log file of "sqrl.ver.grc.com" since the system was started to the successful reply:

23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] query: ::1 sqrl.ver.grc.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_new
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query sqrl.ver.grc.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: resolving sqrl.ver.grc.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: sending query: sqrl.ver.grc.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2001:4860:4860::8888#853
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=144401 rrset=152300 infra=10796 val=75387
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: tcp error for address 2001:4860:4860::8888 port 853
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: query sqrl.ver.grc.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: sending query: sqrl.ver.grc.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 8.8.4.4#853
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=144401 rrset=152300 infra=10796 val=75387
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: tcp error for address 8.8.4.4 port 853
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: query sqrl.ver.grc.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: sending query: sqrl.ver.grc.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 8.8.8.8#853
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=144401 rrset=152300 infra=10796 val=75387
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] query: ::1 livetileedge.dsx.mp.microsoft.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_new
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query livetileedge.dsx.mp.microsoft.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: resolving livetileedge.dsx.mp.microsoft.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: livetileedge.dsx.mp.microsoft.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: sending query: livetileedge.dsx.mp.microsoft.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2606:4700:4700::1001#853
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=144401 rrset=152300 infra=10796 val=75387
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] query: ::1 livetileedge.dsx.mp.microsoft.com. AAAA IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_new
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query livetileedge.dsx.mp.microsoft.com. AAAA IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: resolving livetileedge.dsx.mp.microsoft.com. AAAA IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: livetileedge.dsx.mp.microsoft.com. AAAA IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: sending query: livetileedge.dsx.mp.microsoft.com. AAAA IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2606:4700:4700::1001#853
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=144401 rrset=152300 infra=10796 val=75387
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: tcp error for address 8.8.8.8 port 853
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: query sqrl.ver.grc.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: Capsforid: timeouts, starting fallback
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: sending query: sqrl.ver.grc.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2001:4860:4860::8844#853
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=144401 rrset=152300 infra=10796 val=75387
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: query livetileedge.dsx.mp.microsoft.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: sanitize: removing extraneous answer RRset: livetileedge.xbetservices.akadns.net. CNAME IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: sanitize: removing extraneous answer RRset: livetileedge.dsx.mp.microsoft.com.edgekey.net. CNAME IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: sanitize: removing extraneous answer RRset: e16646.dspg.akamaiedge.net. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: response for livetileedge.dsx.mp.microsoft.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 2606:4700:4700::1001#853
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: query response was CNAME
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: resolving livetileedge.dsx.mp.microsoft.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: livetileedge.dsx.mp.microsoft.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: sending query: livetileedge.xbetservices.akadns.net. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 8.8.8.8#853
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=144401 rrset=152543 infra=10796 val=75387
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: query livetileedge.dsx.mp.microsoft.com. AAAA IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: sanitize: removing extraneous answer RRset: livetileedge.xbetservices.akadns.net. CNAME IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: sanitize: removing extraneous answer RRset: livetileedge.dsx.mp.microsoft.com.edgekey.net. CNAME IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: sanitize: removing extraneous answer RRset: e16646.dspg.akamaiedge.net. AAAA IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: response for livetileedge.dsx.mp.microsoft.com. AAAA IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 2606:4700:4700::1001#853
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: query response was CNAME
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: resolving livetileedge.dsx.mp.microsoft.com. AAAA IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: livetileedge.dsx.mp.microsoft.com. AAAA IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: sending query: livetileedge.xbetservices.akadns.net. AAAA IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 8.8.8.8#853
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=144401 rrset=152543 infra=10796 val=75387
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: query livetileedge.dsx.mp.microsoft.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: chased to livetileedge.xbetservices.akadns.net. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: sanitize: removing extraneous answer RRset: livetileedge.dsx.mp.microsoft.com.edgekey.net. CNAME IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: sanitize: removing extraneous answer RRset: e16646.dspg.akamaiedge.net. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: response for livetileedge.dsx.mp.microsoft.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 8.8.8.8#853
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: query response was CNAME
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: resolving livetileedge.dsx.mp.microsoft.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: livetileedge.dsx.mp.microsoft.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: sending query: livetileedge.dsx.mp.microsoft.com.edgekey.net. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2606:4700:4700::1111#853
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=144401 rrset=152798 infra=10796 val=75387
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: query livetileedge.dsx.mp.microsoft.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: chased to livetileedge.dsx.mp.microsoft.com.edgekey.net. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: sanitize: removing extraneous answer RRset: e16646.dspg.akamaiedge.net. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: response for livetileedge.dsx.mp.microsoft.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 2606:4700:4700::1111#853
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: query response was CNAME
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: resolving livetileedge.dsx.mp.microsoft.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: livetileedge.dsx.mp.microsoft.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: sending query: e16646.dspg.akamaiedge.net. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2606:4700:4700::1111#853
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=144401 rrset=153043 infra=10796 val=75387
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: tcp error for address 2001:4860:4860::8844 port 853
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: query sqrl.ver.grc.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: sending query: sqrl.ver.grc.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 1.1.1.1#853
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=144401 rrset=153043 infra=10796 val=75387
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: query livetileedge.dsx.mp.microsoft.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: chased to e16646.dspg.akamaiedge.net. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: response for livetileedge.dsx.mp.microsoft.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 2606:4700:4700::1111#853
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: query response was ANSWER
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: finishing processing for livetileedge.dsx.mp.microsoft.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query livetileedge.dsx.mp.microsoft.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_pass
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query microsoft.com. DS IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: resolving microsoft.com. DS IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: finishing processing for microsoft.com. DS IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query microsoft.com. DS IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: NSEC3s for the referral proved no DS.
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_wait_subquery event:module_event_pass
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query livetileedge.dsx.mp.microsoft.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: Verified that unsigned response is INSECURE
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_pass
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query akadns.net. DS IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: resolving akadns.net. DS IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: finishing processing for akadns.net. DS IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query akadns.net. DS IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: NSEC3s for the referral proved no DS.
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_wait_subquery event:module_event_pass
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query livetileedge.dsx.mp.microsoft.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: chased to livetileedge.xbetservices.akadns.net. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: Verified that unsigned response is INSECURE
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_pass
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query edgekey.net. DS IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: resolving edgekey.net. DS IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: finishing processing for edgekey.net. DS IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query edgekey.net. DS IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: NSEC3s for the referral proved no DS.
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_wait_subquery event:module_event_pass
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query livetileedge.dsx.mp.microsoft.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: chased to livetileedge.dsx.mp.microsoft.com.edgekey.net. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: Verified that unsigned response is INSECURE
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_pass
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query akamaiedge.net. DS IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: resolving akamaiedge.net. DS IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: finishing processing for akamaiedge.net. DS IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query akamaiedge.net. DS IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: NSEC3s for the referral proved no DS.
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_wait_subquery event:module_event_pass
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query livetileedge.dsx.mp.microsoft.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: chased to e16646.dspg.akamaiedge.net. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: Verified that unsigned response is INSECURE
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] reply: ::1 livetileedge.dsx.mp.microsoft.com. A IN NOERROR 0.169318 0 210
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=144920 rrset=153245 infra=10796 val=75387
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: query livetileedge.dsx.mp.microsoft.com. AAAA IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: chased to livetileedge.xbetservices.akadns.net. AAAA IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: sanitize: removing extraneous answer RRset: livetileedge.dsx.mp.microsoft.com.edgekey.net. CNAME IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: sanitize: removing extraneous answer RRset: e16646.dspg.akamaiedge.net. AAAA IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: response for livetileedge.dsx.mp.microsoft.com. AAAA IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 8.8.8.8#853
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: query response was CNAME
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: resolving livetileedge.dsx.mp.microsoft.com. AAAA IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: resolving livetileedge.dsx.mp.microsoft.com. AAAA IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: livetileedge.dsx.mp.microsoft.com. AAAA IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: sending query: e16646.dspg.akamaiedge.net. AAAA IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2001:4860:4860::8888#853
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=144920 rrset=153245 infra=10796 val=75387
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: query livetileedge.dsx.mp.microsoft.com. AAAA IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: chased to e16646.dspg.akamaiedge.net. AAAA IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: response for livetileedge.dsx.mp.microsoft.com. AAAA IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 2001:4860:4860::8888#853
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: query response was ANSWER
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: finishing processing for livetileedge.dsx.mp.microsoft.com. AAAA IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query livetileedge.dsx.mp.microsoft.com. AAAA IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] reply: ::1 livetileedge.dsx.mp.microsoft.com. AAAA IN NOERROR 0.250807 0 250
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=145439 rrset=153501 infra=10796 val=75387
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: tcp error for address 1.1.1.1 port 853
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: query sqrl.ver.grc.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: sending query: sqrl.ver.grc.com. A IN
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2606:4700:4700::1111#853
23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=145439 rrset=153501 infra=10796 val=75387
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: query sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: response for sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 2606:4700:4700::1111#853
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: Capsforid: starting fallback
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: sending query: sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2001:4860:4860::8888#853
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=145439 rrset=153501 infra=10796 val=75387
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: tcp error for address 2001:4860:4860::8888 port 853
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: query sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: sending query: sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2606:4700:4700::1111#853
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=145439 rrset=153501 infra=10796 val=75387
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: tcp error for address 2606:4700:4700::1111 port 853
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: query sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: sending query: sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 8.8.8.8#853
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=145439 rrset=153501 infra=10796 val=75387
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] query: 127.0.0.1 sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=145439 rrset=153501 infra=10796 val=75387
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: tcp error for address 8.8.8.8 port 853
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: query sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: sending query: sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 1.0.0.1#853
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=145439 rrset=153501 infra=10796 val=75387
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: query sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: response for sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 1.0.0.1#853
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: Capsforid: starting fallback
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: sending query: sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 8.8.4.4#853
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=145439 rrset=153501 infra=10796 val=75387
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: query sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: response for sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 8.8.4.4#853
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: Capsforid: reply is equal. go to next fallback
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: sending query: sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2620:fe::fe#853
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=145439 rrset=153501 infra=10796 val=75387
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: tcp error for address 2620:fe::fe port 853
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: query sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: sending query: sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 9.9.9.9#853
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=145439 rrset=153501 infra=10796 val=75387
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] query: ::1 client.dropbox.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_new
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query client.dropbox.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: resolving client.dropbox.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: client.dropbox.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: sending query: client.dropbox.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 1.1.1.1#853
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=145439 rrset=153501 infra=10796 val=75387
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] query: ::1 client.dropbox.com. AAAA IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_new
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query client.dropbox.com. AAAA IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: resolving client.dropbox.com. AAAA IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: client.dropbox.com. AAAA IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: sending query: client.dropbox.com. AAAA IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2620:fe::fe#853
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=145439 rrset=153501 infra=10796 val=75387
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: query client.dropbox.com. AAAA IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: sanitize: removing extraneous answer RRset: client-env.dropbox-dns.com. AAAA IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: response for client.dropbox.com. AAAA IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 2620:fe::fe#853
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: query response was CNAME
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: resolving client.dropbox.com. AAAA IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: finishing processing for client.dropbox.com. AAAA IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query client.dropbox.com. AAAA IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_pass
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query dropbox.com. DS IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: resolving dropbox.com. DS IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: finishing processing for dropbox.com. DS IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query dropbox.com. DS IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: NSEC3s for the referral proved no DS.
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_wait_subquery event:module_event_pass
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query client.dropbox.com. AAAA IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: Verified that unsigned response is INSECURE
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: validate(positive): sec_status_secure
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] reply: ::1 client.dropbox.com. AAAA IN NOERROR 0.037768 0 101
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=145439 rrset=153501 infra=10796 val=75387
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: query client.dropbox.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: sanitize: removing extraneous answer RRset: client-env.dropbox-dns.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: response for client.dropbox.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 1.1.1.1#853
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: query response was CNAME
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: resolving client.dropbox.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: finishing processing for client.dropbox.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query client.dropbox.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: validate(positive): sec_status_secure
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] reply: ::1 client.dropbox.com. A IN NOERROR 0.037768 0 89
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=145439 rrset=153501 infra=10796 val=75387
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: tcp error for address 9.9.9.9 port 853
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: query sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: sending query: sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2001:4860:4860::8844#853
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=145439 rrset=153501 infra=10796 val=75387
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: query sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: response for sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 2001:4860:4860::8844#853
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: Capsforid: reply is equal. go to next fallback
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: sending query: sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2001:4860:4860::8888#853
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=145439 rrset=153501 infra=10796 val=75387
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: query sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: response for sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 2001:4860:4860::8888#853
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: Capsforid: reply is equal. go to next fallback
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: sending query: sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 8.8.8.8#853
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=145439 rrset=153501 infra=10796 val=75387
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: query sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: response for sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 8.8.8.8#853
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: Capsforid: reply is equal. go to next fallback
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: sending query: sqrl.ver.grc.com. A IN
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 9.9.9.9#853
23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=145439 rrset=153501 infra=10796 val=75387
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: tcp error for address 9.9.9.9 port 853
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: query sqrl.ver.grc.com. A IN
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: request sqrl.ver.grc.com. has exceeded the maximum number of glue fetches 17 to a single delegation point
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: return error response SERVFAIL
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query sqrl.ver.grc.com. A IN
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] error: SERVFAIL <sqrl.ver.grc.com. A IN>: could not fetch nameservers for 0x20 fallback
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] reply: 127.0.0.1 sqrl.ver.grc.com. A IN SERVFAIL 0.959116 0 34
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] reply: ::1 sqrl.ver.grc.com. A IN SERVFAIL 1.977436 0 34
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=145439 rrset=153501 infra=10796 val=75387
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] query: ::1 sqrl.ver.grc.com. A IN
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_new
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query sqrl.ver.grc.com. A IN
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: resolving sqrl.ver.grc.com. A IN
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: sending query: sqrl.ver.grc.com. A IN
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 8.8.4.4#853
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=145439 rrset=153501 infra=10796 val=75387
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: tcp error for address 8.8.4.4 port 853
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: query sqrl.ver.grc.com. A IN
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: sending query: sqrl.ver.grc.com. A IN
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2620:fe::fe#853
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=145439 rrset=153501 infra=10796 val=75387
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: tcp error for address 2620:fe::fe port 853
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_noreply
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: query sqrl.ver.grc.com. A IN
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: sending query: sqrl.ver.grc.com. A IN
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 1.0.0.1#853
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=145439 rrset=153501 infra=10796 val=75387
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: query sqrl.ver.grc.com. A IN
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: response for sqrl.ver.grc.com. A IN
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 1.0.0.1#853
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: query response was ANSWER
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: finishing processing for sqrl.ver.grc.com. A IN
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query sqrl.ver.grc.com. A IN
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_state_initial event:module_event_pass
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query grc.com. DS IN
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_state_initial event:module_event_pass
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: resolving grc.com. DS IN
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: grc.com. DS IN
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: sending query: grc.com. DS IN
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2606:4700:4700::1001#853
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: cache memory msg=145649 rrset=153693 infra=10796 val=75387
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: iterator[module 1] operate: extstate:module_wait_reply event:module_event_reply
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: iterator operate: query grc.com. DS IN
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: response for grc.com. DS IN
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 2606:4700:4700::1001#853
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: query response was nodata ANSWER
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: finishing processing for grc.com. DS IN
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_wait_module event:module_event_moddone
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query grc.com. DS IN
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: NSEC3s for the referral proved no DS.
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: validator[module 0] operate: extstate:module_wait_subquery event:module_event_pass
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: validator operate: query sqrl.ver.grc.com. A IN
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: Verified that unsigned response is INSECURE
23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] reply: ::1 sqrl.ver.grc.com. A IN NOERROR 0.343845 0 50

This is an edit of the file looking for the "interesting" entries, it is surprising how many "tcp error" items there are - are these servers that busy?:

       6 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
       8 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2001:4860:4860::8888#853
      10 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: tcp error for address 2001:4860:4860::8888 port 853
      13 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
      15 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 8.8.4.4#853
      17 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: tcp error for address 8.8.4.4 port 853
      20 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
      22 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 8.8.8.8#853
      29 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: livetileedge.dsx.mp.microsoft.com. A IN
      31 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2606:4700:4700::1001#853
      38 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: livetileedge.dsx.mp.microsoft.com. AAAA IN
      40 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2606:4700:4700::1001#853
      42 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: tcp error for address 8.8.8.8 port 853
      45 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: Capsforid: timeouts, starting fallback
      46 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
      48 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2001:4860:4860::8844#853
      56 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 2606:4700:4700::1001#853
      59 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: livetileedge.dsx.mp.microsoft.com. A IN
      61 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 8.8.8.8#853
      69 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 2606:4700:4700::1001#853
      72 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: livetileedge.dsx.mp.microsoft.com. AAAA IN
      74 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 8.8.8.8#853
      82 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 8.8.8.8#853
      85 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: livetileedge.dsx.mp.microsoft.com. A IN
      87 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2606:4700:4700::1111#853
      94 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 2606:4700:4700::1111#853
      97 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: livetileedge.dsx.mp.microsoft.com. A IN
      99 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2606:4700:4700::1111#853
     101 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: tcp error for address 2001:4860:4860::8844 port 853
     104 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
     106 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 1.1.1.1#853
     112 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 2606:4700:4700::1111#853
     172 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 8.8.8.8#853
     176 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: livetileedge.dsx.mp.microsoft.com. AAAA IN
     178 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2001:4860:4860::8888#853
     184 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 2001:4860:4860::8888#853
     191 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: tcp error for address 1.1.1.1 port 853
     194 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
     196 23/07/2020 15:06:51 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2606:4700:4700::1111#853
     201 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 2606:4700:4700::1111#853
     202 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: Capsforid: starting fallback
     203 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
     205 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2001:4860:4860::8888#853
     207 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: tcp error for address 2001:4860:4860::8888 port 853
     210 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
     212 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2606:4700:4700::1111#853
     214 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: tcp error for address 2606:4700:4700::1111 port 853
     217 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
     219 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 8.8.8.8#853
     223 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: tcp error for address 8.8.8.8 port 853
     226 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
     228 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 1.0.0.1#853
     233 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 1.0.0.1#853
     234 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: Capsforid: starting fallback
     235 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
     237 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 8.8.4.4#853
     242 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 8.8.4.4#853
     243 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: Capsforid: reply is equal. go to next fallback
     244 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
     246 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2620:fe::fe#853
     248 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: tcp error for address 2620:fe::fe port 853
     251 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
     253 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 9.9.9.9#853
     260 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: client.dropbox.com. A IN
     262 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 1.1.1.1#853
     269 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: client.dropbox.com. AAAA IN
     271 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2620:fe::fe#853
     277 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 2620:fe::fe#853
     301 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 1.1.1.1#853
     310 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: tcp error for address 9.9.9.9 port 853
     313 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
     315 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2001:4860:4860::8844#853
     320 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 2001:4860:4860::8844#853
     321 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: Capsforid: reply is equal. go to next fallback
     322 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
     324 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2001:4860:4860::8888#853
     329 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 2001:4860:4860::8888#853
     330 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: Capsforid: reply is equal. go to next fallback
     331 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
     333 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 8.8.8.8#853
     338 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 8.8.8.8#853
     339 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: Capsforid: reply is equal. go to next fallback
     340 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
     342 23/07/2020 15:06:52 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 9.9.9.9#853
     344 23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: tcp error for address 9.9.9.9 port 853
     347 23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
     349 23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: return error response SERVFAIL
     352 23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] error: SERVFAIL <sqrl.ver.grc.com. A IN>: could not fetch nameservers for 0x20 fallback
     353 23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] reply: 127.0.0.1 sqrl.ver.grc.com. A IN SERVFAIL 0.959116 0 34
     354 23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] reply: ::1 sqrl.ver.grc.com. A IN SERVFAIL 1.977436 0 34
     361 23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
     363 23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 8.8.4.4#853
     365 23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: tcp error for address 8.8.4.4 port 853
     368 23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
     370 23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2620:fe::fe#853
     372 23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: tcp error for address 2620:fe::fe port 853
     375 23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: sqrl.ver.grc.com. A IN
     377 23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 1.0.0.1#853
     382 23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 1.0.0.1#853
     391 23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: processQueryTargets: grc.com. DS IN
     393 23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] debug: sending to target: <.> 2606:4700:4700::1001#853
     398 23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] info: reply from <.> 2606:4700:4700::1001#853
     407 23/07/2020 15:06:53 C:\Program Files\Unbound\unbound.exe[1804:0] reply: ::1 sqrl.ver.grc.com. A IN NOERROR 0.343845 0 50

Thanks

Ray

Hi Ray,

Hi George,

OK thanks for the confirmation of the other issues I have seen.

With respect to the use-caps-for-id when you say "as long as the other side supports it" CloudFlare does support it because it works most of the time.

You also said:

"When unbound asks for an.ExaMple.domAin.NeT and the record is not cached in the forwarder, the answer will contain the correct case.
Afterwards, when the answer is cached, the wrong casing (always lowercase) will be used, and until the TTL expires I assume.
This results in a mismatch between query and reply if use-caps-for-id is used."

So am I right in the understanding that the initial query goes to CloudFlare and CloudFlare has to go off and resolve the name itself (its not in the cache) then the correct case is preserved. Now CloudFlare has the name stored in its cache. A subsequent query for the same DNS name is then requested where the casing is randomly different and then a case insensitive match is found in CloudFlare's cache and the cache entry casing is returned rather than the query with the different casing that the client just requested. Then you get the error. This sounds like a bug to me and something CloudFlare should be aware of and fix? Is that what you were referring to when you said "I will try to reach the people involved"?

Indeed.
On second observation though, the cloudflare resolver seems to reply
properly for 0x20 (use-caps-for-id) queries.
The QUESTION section is always consistent (copied) and is the one that
matters for 0x20 support.
The other sections (i.e., ANSWER) always return lowercase from cache.
This is uncommon with other resolvers' behavior (but not wrong) and it
was what tricked me previously.

For the "tcp error" it would be nice to have a bit more information in the log as to what actually failed e.g. connection refused, timed out etc. It would help to show it was the other end not Unbound.

I was using just CloudFlare to make sure that when I submitted the logs etc. that I had a repeatable, consistent issue to look at. And yes since setting 'use-caps-for-id' to 'no' there have been no issues.

This is good to know. It verifies that the issue is indeed related to 0x20.

I have now set 'use-caps-for-id' to 'yes' and changed the list of forwarders to see what happens:
From your latest email we see that the issue keeps happening even for

other resolvers. What I gather from your log is that due to the tcp
errors (timeouts), unbound sees that there is no answer yet for the 0x20
query and starts the 0x20 fallback. This ultimately results in failure
(SERVFAIL) to the client.

Upon closer inspection I see the following is happening:
1. Unbound tries to resolve a domain and performs qname perturbation
   because of 0x20.
2. Due to tcp errors (timeouts) from the otherside and because unbound
   is configured with 0x20, the fallback logic is triggered.
   "Capsforid: timeouts, starting fallback"
2b. (From now on unbound does not care about the proper case.)
3. Unbound eventually gets the first response that will try to match
   with other replies.
   "Capsforid: starting fallback"
4. Unbound asks other upstream servers and tries to match the reply to
   make sure that what it got on the previous step is the same as with
   other servers/replies.
   "Capsforid: reply is equal. go to next fallback"
5. Unbound will try to get at least x*3 identical replies to make sure
   that the reply is indeed consistent between different upstreams.
   x is the number of addresses for that delegation point (number
   of IPs in the forward section in your case).
6. In your case, and because of the timeouts in between, this number
   cannot be reached before the limit of the glue fetches per delegation
   point(16) is reached, which terminates the query.

This is only happening randomly because in order for the 0x20 fallback
logic to kick in you need either:
- Wrong case in the reply (not your case), or
- No answer (timeouts) at least 3 times.

So if you want to avoid the random SERVFAILS I would suggest to turn off
use-caps-for-id.

0x20 is meant to increase the random bits of query_ids by perturbating
the letter case of the qname. This is to make cache poisoning more
difficult and is more relevant to UDP.

In your case, where you send all queries to specific resolvers over TLS,
you can safely remove that.

I suppose you use DoT for privacy, so I would also use `forward-first:
no` (default) to make sure that unbound does not leak any queries to
other servers except the ones you have specified over DoT.

I hope that explains things,
-- George

George,

Thanks for the detailed reply, I now better understand what is going on.

I hope others also found this thread of benefit.

I have now upgraded unbound to 1.11.0 and so far all is looking good.

There are still some more things I want things to test but having the explanation below helps a lot.

Thanks

Ray