[Dnsmasq-discuss] Infinite loop upon incoming DNS query when no upstream resolvers during cable modem reboot

Wed, 23 Feb 2022 01:39:09 -0800

This has happened to me a few times now. Dnsmasq 2.86 gets into an infinite loop and ceases all DNS and DHCP service when my cable modem reboots and hands out a junk DHCP lease with no DNS resolvers. 

Some clues:
* /etc/resolv.conf is static and contains only "nameserver 127.0.0.1" so programs running on the local host will resolve names via the local Dnsmasq instance.
* /tmp/resolv.conf is generated by the DHCP client script and usually contains: 

   domain hsd1.nh.comcast.net.
   nameserver 75.75.75.75
   nameserver 75.75.76.76

* /etc/dnsmasq.conf contains:

   no-hosts
   log-queries=extra
   bogus-priv
   resolv-file=/tmp/resolv.conf
   domain-needed
   cache-size=10000
   dhcp-range=192.168.1.1,192.168.1.254,255.255.254.0,720h
   dhcp-range=::,constructor:br0,ra-only,ra-names
   dhcp-option=vendor:MSFT,1,2i
   dhcp-authoritative
   dhcp-rapid-commit
   log-dhcp
   dhcp-script=/bin/logger
   script-on-renewal
   enable-ra
   ra-param=br0,mtu:eth0,high,1800,9000
domain=home.xxxxxx.com 
   local=/home.xxxxxx.com/
   cname=home.xxxxxx.com,yyyyyy.home.xxxxxx.com,86400
   address=/public.home.xxxxxx.com/
   interface-name=public.home.xxxxxx.com,eth0/4
* Dnsmasq is started by init as "/sbin/dnsmasq --keep-in-foreground --user --interface=br0".
* /tmp/resolv.conf becomes empty when the cable modem hands out a junk DHCP lease with no DNS resolvers.
* One of the last messages Dnsmasq logs before it hangs is "no servers found in /tmp/resolv.conf, will retry".
* The DHCP client script tries to synchronize the local clock with pool.ntp.org upon every lease renewal. This triggers a DNS query, and as /etc/resolv.conf says "nameserver 127.0.0.1", the query goes to Dnsmasq to resolve. Dnsmasq logs the query but then logs *neither* a response to the query *nor* an indication that the query has been forwarded anywhere. Indeed, this appears to be the point when Dnsmasq goes into an infinite loop and never comes back.
* When this happens, Dnsmasq pegs the CPU and does not respond to SIGTERM. I have to SIGKILL it.
Some key points from the log (which is included in full at the end of this message): 

1. <6>r8169 0000:02:00.0 eth0: Link is Up - 1Gbps/Full - flow control off
  <30>dhcpcd[82]: eth0: carrier acquired
This is the Ethernet link to the cable modem reacquiring carrier after the cable modem reboots.
2. <31>dhcpcd[82]: eth0: sending DISCOVER (xid 0xbd6b2cf7), next in 4.2 seconds 
  <30>dhcpcd[82]: eth0: offered 192.168.100.10 from 192.168.100.1
<31>dhcpcd[82]: eth0: sending REQUEST (xid 0xbd6b2cf7), next in 4.5 seconds 
  <31>dhcpcd[82]: eth0: acknowledged 192.168.100.10 from 192.168.100.1
This is the DHCP client obtaining a junk lease from the cable modem, which is has not yet locked onto the DOCSIS network at this point. 

3. <31>dhcpcd[82]: eth0: executing: /etc/dhcpcd.sh BOUND
  <13>dhcp: Upstream routers: 192.168.100.1
  <13>dhcp: Domain name: (none)
  <13>dhcp: DNS servers: (none)
  <28>dnsmasq[14692]: no servers found in /tmp/resolv.conf, will retry
This is the DHCP client script updating /tmp/resolv.conf (to become empty) and Dnsmasq noticing the change, re-reading the file, and reporting that there are no upstream servers. 

4. <13>dhcp: NTP servers: (none)
<30>dnsmasq[14692]: 15019 127.0.0.1/50561 query[A] pool.ntp.org from 127.0.0.1
This is the DHCP client script reporting that the DHCP lease has specified no NTP servers. At this point the script calls ntpclient with a default NTP server of pool.ntp.org. You can see the DNS query arrive at Dnsmasq from localhost.
5. <75>ntpclient[28632]: Failed resolving address to hostname pool.ntp.org: No address associated with hostname 
  <75>ntpclient[28632]: Unable lookup pool.ntp.org
This is ntpclient complaining that it was unable to resolve pool.ntp.org. Notably, there was *no* log line from Dnsmasq stating, "config error is REFUSED (EDE: network error)". Rather, it appears as though ntpclient simply timed out while waiting for a reply. 

6. <31>dhcpcd[82]: eth0: executing: /etc/dhcpcd.sh RENEW
  <13>dhcp: NTP servers: (none)
<75>ntpclient[28638]: Failed resolving address to hostname pool.ntp.org: No address associated with hostname 
  <75>ntpclient[28638]: Unable lookup pool.ntp.org
This is the DHCP client renewing the junk lease, and the DHCP client script trying again with ntpclient, which still fails. Notably, this time there is no DNS query logged by Dnsmasq. Indeed, Dnsmasq is out to lunch at this point and will not be coming back. 

7. <28>dhcpcd[82]: eth0: failed to renew DHCP, rebinding
  <31>dhcpcd[82]: eth0: expire in 3 seconds
<31>dhcpcd[82]: eth0: sending REQUEST (xid 0xdfa6a085), next in 3.2 seconds 
  <28>dhcpcd[82]: eth0: NAK: from 96.113.192.209
  <31>dhcpcd[82]: eth0: deleting IP address 192.168.100.10/24
  <30>dhcpcd[82]: eth0: deleting route to 192.168.100.0/24
  <30>dhcpcd[82]: eth0: deleting default route via 192.168.100.1
  <31>dhcpcd[82]: eth0: executing: /etc/dhcpcd.sh NAK
  <30>dhcpcd[82]: eth0: soliciting a DHCP lease (requesting 73.###.###.56)
<31>dhcpcd[82]: eth0: sending DISCOVER (xid 0x423103f5), next in 3.9 seconds 
  <30>dhcpcd[82]: eth0: offered 73.###.###.246 from 96.113.192.209
<31>dhcpcd[82]: eth0: sending REQUEST (xid 0x423103f5), next in 4.0 seconds 
  <31>dhcpcd[82]: eth0: acknowledged 73.###.###.246 from 96.113.192.209
This is the DHCP client failing to renew the junk lease from the cable modem and instead obtaining a real lease from Comcast. 

8. <31>dhcpcd[82]: eth0: executing: /etc/dhcpcd.sh BOUND
  <13>dhcp: Upstream routers: 73.###.###.1
  <13>dhcp: Domain name: hsd1.nh.comcast.net.
  <13>dhcp: DNS servers: 75.75.75.75 75.75.76.76
  <13>dhcp: NTP servers: (none)
<75>ntpclient[28674]: Failed resolving address to hostname pool.ntp.org: No address associated with hostname 
  <75>ntpclient[28674]: Unable lookup pool.ntp.org
This is the DHCP client script updating /tmp/resolv.conf with the real domain name and name servers. Notably, Dnsmasq does not notice the change in /tmp/resolv.conf since it is feverishly engaged in chasing its own tail. Further, you can see that ntpclient still failed to resolve pool.ntp.org even though the Internet connection is now up and /tmp/resolv.conf now specifies working upstream name servers.
Below is the full log from just after the cable modem went down — sorry, I lost the first few log lines, but they're unimportant to this issue — until just after Internet connectivity was restored. 


<30>dhcpcd[82]: lo: adding reject route to 2601:####:####:####::/64
<30>dhcpcd[82]: br0: deleting route to 2601:####:####:####::/64
<30>dhcpcd[82]: lo: deleting reject route to 2601:####:####:####::/64
<31>dhcpcd[82]: eth0: executing: /etc/dhcpcd.sh EXPIRE6
<30>dhcpcd[82]: eth0: deleting default route via fe80::dead:beef:6:1
<31>dhcpcd[82]: eth0: executing: /etc/dhcpcd.sh ROUTERADVERT
<30>dhcpcd[82]: eth0: deleting address fe80::2##:##ff:fe##:##7c
<31>dhcpcd[82]: eth0: deleting IP address 73.###.###.246/23
<30>dnsmasq-dhcp[14692]: RTR-ADVERT(br0) 2601:####:####:####:: old prefix
<30>dhcpcd[82]: eth0: deleting route to 73.###.###.0/23
<30>dhcpcd[82]: eth0: deleting default route via 73.###.###.1
<31>dhcpcd[82]: eth0: executing: /etc/dhcpcd.sh EXPIRE
<30>dnsmasq[14692]: 14951 192.168.1.34/51346 query[A] docs.google.com from 192.168.1.34 <30>dnsmasq[14692]: 14951 192.168.1.34/51346 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14952 fe80::2##:##ff:fe##:##2f%br0/39732 query[A] docs.google.com from fe80::2##:##ff:fe##:##2f <30>dnsmasq[14692]: 14952 fe80::2##:##ff:fe##:##2f%br0/39732 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14953 fe80::2##:##ff:fe##:##2f%br0/39732 query[AAAA] docs.google.com from fe80::2##:##ff:fe##:##2f <30>dnsmasq[14692]: 14953 fe80::2##:##ff:fe##:##2f%br0/39732 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14954 192.168.1.194/42556 query[A] epdg.epc.mnc260.mcc310.pub.3gppnetwork.org from 192.168.1.194 <30>dnsmasq[14692]: 14954 192.168.1.194/42556 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14955 192.168.1.34/43225 query[A] docs.google.com from 192.168.1.34 <30>dnsmasq[14692]: 14955 192.168.1.34/43225 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14956 192.168.1.34/43225 query[AAAA] docs.google.com from 192.168.1.34 <30>dnsmasq[14692]: 14956 192.168.1.34/43225 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14957 fe80::2##:##ff:fe##:##2f%br0/36765 query[A] docs.google.com from fe80::2##:##ff:fe##:##2f <30>dnsmasq[14692]: 14957 fe80::2##:##ff:fe##:##2f%br0/36765 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14958 fe80::2##:##ff:fe##:##2f%br0/36765 query[AAAA] docs.google.com from fe80::2##:##ff:fe##:##2f <30>dnsmasq[14692]: 14958 fe80::2##:##ff:fe##:##2f%br0/36765 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14959 192.168.1.34/51539 query[A] docs.google.com.home.xxxxxx.com from 192.168.1.34 <30>dnsmasq[14692]: 14959 192.168.1.34/51539 config docs.google.com.home.xxxxxx.com is NXDOMAIN <30>dnsmasq[14692]: 14960 192.168.1.34/51539 query[AAAA] docs.google.com.home.xxxxxx.com from 192.168.1.34 <30>dnsmasq[14692]: 14960 192.168.1.34/51539 config docs.google.com.home.xxxxxx.com is NXDOMAIN <30>dnsmasq[14692]: 14961 192.168.1.34/38108 query[A] ssl.gstatic.com.home.xxxxxx.com from 192.168.1.34 <30>dnsmasq[14692]: 14961 192.168.1.34/38108 config ssl.gstatic.com.home.xxxxxx.com is NXDOMAIN <30>dnsmasq[14692]: 14962 192.168.1.34/38108 query[AAAA] ssl.gstatic.com.home.xxxxxx.com from 192.168.1.34 <30>dnsmasq[14692]: 14962 192.168.1.34/38108 config ssl.gstatic.com.home.xxxxxx.com is NXDOMAIN <30>dnsmasq[14692]: 14963 192.168.1.34/52427 query[A] mtalk.google.com from 192.168.1.34 <30>dnsmasq[14692]: 14963 192.168.1.34/52427 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14964 fe80::2##:##ff:fe##:##2f%br0/54366 query[A] mtalk.google.com from fe80::2##:##ff:fe##:##2f <30>dnsmasq[14692]: 14964 fe80::2##:##ff:fe##:##2f%br0/54366 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14965 192.168.1.34/48927 query[A] mtalk.google.com from 192.168.1.34 <30>dnsmasq[14692]: 14965 192.168.1.34/48927 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14966 192.168.1.34/34707 query[A] clients4.google.com from 192.168.1.34 <30>dnsmasq[14692]: 14966 192.168.1.34/34707 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14967 fe80::2##:##ff:fe##:##2f%br0/54213 query[A] mtalk.google.com from fe80::2##:##ff:fe##:##2f <30>dnsmasq[14692]: 14967 fe80::2##:##ff:fe##:##2f%br0/54213 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14968 192.168.1.34/58405 query[A] mtalk.google.com.home.xxxxxx.com from 192.168.1.34 <30>dnsmasq[14692]: 14968 192.168.1.34/58405 config mtalk.google.com.home.xxxxxx.com is NXDOMAIN 
<6>r8169 0000:02:00.0 eth0: Link is Up - 1Gbps/Full - flow control off
<30>dhcpcd[82]: eth0: carrier acquired
<31>dhcpcd[82]: eth0: executing: /etc/dhcpcd.sh CARRIER
<30>dhcpcd[82]: eth0: IAID ##:##:##:7c
<30>dhcpcd[82]: eth0: IA type 25 IAID 00:00:00:00
<30>dhcpcd[82]: eth0: adding address fe80::2##:##ff:fe##:##7c
<31>dhcpcd[82]: eth0: pltime infinity, vltime infinity
<31>dhcpcd[82]: eth0: delaying IPv6 router solicitation for 0.0 seconds
<31>dhcpcd[82]: eth0: delaying DHCPv6 for LL address
<31>dhcpcd[82]: eth0: delaying IPv4 for 1.7 seconds
<30>dhcpcd[82]: eth0: soliciting an IPv6 router
<31>dhcpcd[82]: eth0: delaying Router Solicitation for LL address
<30>dnsmasq[14692]: 14969 192.168.1.34/59404 query[A] play.google.com.home.xxxxxx.com from 192.168.1.34 <30>dnsmasq[14692]: 14969 192.168.1.34/59404 config play.google.com.home.xxxxxx.com is NXDOMAIN <30>dnsmasq[14692]: 14970 192.168.1.34/59404 query[AAAA] play.google.com.home.xxxxxx.com from 192.168.1.34 <30>dnsmasq[14692]: 14970 192.168.1.34/59404 config play.google.com.home.xxxxxx.com is NXDOMAIN <30>dnsmasq[14692]: 14971 192.168.1.34/34463 query[A] docs.google.com.home.xxxxxx.com from 192.168.1.34 <30>dnsmasq[14692]: 14971 192.168.1.34/34463 config docs.google.com.home.xxxxxx.com is NXDOMAIN <30>dnsmasq[14692]: 14972 192.168.1.34/34463 query[AAAA] docs.google.com.home.xxxxxx.com from 192.168.1.34 <30>dnsmasq[14692]: 14972 192.168.1.34/34463 config docs.google.com.home.xxxxxx.com is NXDOMAIN <30>dnsmasq[14692]: 14973 192.168.1.34/49362 query[A] ssl.gstatic.com from 192.168.1.34 <30>dnsmasq[14692]: 14973 192.168.1.34/49362 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14974 192.168.1.34/49362 query[AAAA] ssl.gstatic.com from 192.168.1.34 <30>dnsmasq[14692]: 14974 192.168.1.34/49362 config error is REFUSED (EDE: network error) 
<31>dhcpcd[82]: eth0: using ClientID 01:00:##:##:##:##:7c
<31>dhcpcd[82]: eth0: reading lease: /var/db/dhcpcd/eth0.lease
<30>dhcpcd[82]: eth0: soliciting a DHCP lease (requesting 73.###.###.56)
<31>dhcpcd[82]: eth0: sending DISCOVER (xid 0xbd6b2cf7), next in 4.2 seconds 
<30>dhcpcd[82]: eth0: offered 192.168.100.10 from 192.168.100.1
<31>dhcpcd[82]: eth0: sending REQUEST (xid 0xbd6b2cf7), next in 4.5 seconds
<31>dhcpcd[82]: eth0: acknowledged 192.168.100.10 from 192.168.100.1
<30>dhcpcd[82]: eth0: probing address 192.168.100.10/24
<31>dhcpcd[82]: eth0: probing for 192.168.100.10
<31>dhcpcd[82]: eth0: ARP probing 192.168.100.10 (1 of 3), next in 1.5 seconds <30>dnsmasq[14692]: 14975 192.168.1.39/19941 query[A] bb2.simplisafe.com from 192.168.1.39 <30>dnsmasq[14692]: 14975 192.168.1.39/19941 config error is REFUSED (EDE: network error) 
<31>dhcpcd[82]: eth0: reading lease: /var/db/dhcpcd/eth0.lease6
<30>dhcpcd[82]: eth0: rebinding prior DHCPv6 lease
<31>dhcpcd[82]: eth0: delaying REBIND6 (xid 0x89df3b), next in 1.0 seconds
<31>dhcpcd[82]: eth0: sending Router Solicitation
<30>dnsmasq[14692]: 14976 fe80::2##:##ff:fe##:##2f%br0/33005 query[A] docs.google.com from fe80::2##:##ff:fe##:##2f <30>dnsmasq[14692]: 14976 fe80::2##:##ff:fe##:##2f%br0/33005 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14977 192.168.1.34/39449 query[A] docs.google.com from 192.168.1.34 <30>dnsmasq[14692]: 14977 192.168.1.34/39449 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14978 fe80::2##:##ff:fe##:##2f%br0/46203 query[A] docs.google.com from fe80::2##:##ff:fe##:##2f <30>dnsmasq[14692]: 14978 fe80::2##:##ff:fe##:##2f%br0/46203 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14979 192.168.1.34/41111 query[A] docs.google.com.home.xxxxxx.com from 192.168.1.34 <30>dnsmasq[14692]: 14979 192.168.1.34/41111 config docs.google.com.home.xxxxxx.com is NXDOMAIN <30>dnsmasq[14692]: 14980 192.168.1.34/50058 query[A] docs.google.com.home.xxxxxx.com from 192.168.1.34 <30>dnsmasq[14692]: 14980 192.168.1.34/50058 config docs.google.com.home.xxxxxx.com is NXDOMAIN <30>dnsmasq[14692]: 14981 192.168.1.34/50058 query[AAAA] docs.google.com.home.xxxxxx.com from 192.168.1.34 <30>dnsmasq[14692]: 14981 192.168.1.34/50058 config docs.google.com.home.xxxxxx.com is NXDOMAIN <30>dnsmasq[14692]: 14982 192.168.1.58/49401 query[A] push.prod.netflix.com from 192.168.1.58 <30>dnsmasq[14692]: 14982 192.168.1.58/49401 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14983 192.168.1.58/49401 query[A] nrdp.prod.cloud.netflix.com from 192.168.1.58 <30>dnsmasq[14692]: 14983 192.168.1.58/49401 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14984 192.168.1.58/49401 query[A] nrdp51-appboot.netflix.com from 192.168.1.58 <30>dnsmasq[14692]: 14984 192.168.1.58/49401 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14985 192.168.1.58/49401 query[A] nrdp.nccp.netflix.com from 192.168.1.58 <30>dnsmasq[14692]: 14985 192.168.1.58/49401 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14986 192.168.1.58/49401 query[A] api-global.netflix.com from 192.168.1.58 <30>dnsmasq[14692]: 14986 192.168.1.58/49401 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14987 192.168.1.58/49401 query[A] secure.netflix.com from 192.168.1.58 <30>dnsmasq[14692]: 14987 192.168.1.58/49401 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14988 192.168.1.58/49401 query[A] uiboot.netflix.com from 192.168.1.58 <30>dnsmasq[14692]: 14988 192.168.1.58/49401 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14989 192.168.1.58/49401 query[A] customerevents.netflix.com from 192.168.1.58 <30>dnsmasq[14692]: 14989 192.168.1.58/49401 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14990 192.168.1.58/49401 query[A] ichnaea.netflix.com from 192.168.1.58 <30>dnsmasq[14692]: 14990 192.168.1.58/49401 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14991 192.168.1.58/49401 query[A] cdn-0.nflximg.com from 192.168.1.58 <30>dnsmasq[14692]: 14991 192.168.1.58/49401 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14992 192.168.1.58/34254 query[A] scribe.logs.roku.com from 192.168.1.58 <30>dnsmasq[14692]: 14992 192.168.1.58/34254 config scribe.logs.roku.com is NXDOMAIN <30>dnsmasq[14692]: 14993 192.168.1.58/49401 query[A] nrdp.prod.ftl.netflix.com from 192.168.1.58 <30>dnsmasq[14692]: 14993 192.168.1.58/49401 cached nrdp.prod.ftl.netflix.com is <CNAME> <30>dnsmasq[14692]: 14993 192.168.1.58/49401 config error is REFUSED (EDE: network error) <31>dhcpcd[82]: eth0: broadcasting REBIND6 (xid 0x89df3b), next in 1.1 seconds <31>dhcpcd[82]: eth0: ARP probing 192.168.100.10 (2 of 3), next in 1.0 seconds <31>dhcpcd[82]: eth0: broadcasting REBIND6 (xid 0x89df3b), next in 2.1 seconds <30>dnsmasq[14692]: 14994 192.168.1.34/52662 query[A] play.google.com from 192.168.1.34 <30>dnsmasq[14692]: 14994 192.168.1.34/52662 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14995 fe80::2##:##ff:fe##:##2f%br0/57667 query[A] play.google.com from fe80::2##:##ff:fe##:##2f <30>dnsmasq[14692]: 14995 fe80::2##:##ff:fe##:##2f%br0/57667 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14996 192.168.1.34/53142 query[A] play.google.com from 192.168.1.34 <30>dnsmasq[14692]: 14996 192.168.1.34/53142 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14997 fe80::2##:##ff:fe##:##2f%br0/53711 query[A] play.google.com from fe80::2##:##ff:fe##:##2f <30>dnsmasq[14692]: 14997 fe80::2##:##ff:fe##:##2f%br0/53711 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 14998 192.168.1.34/42242 query[A] play.google.com.home.xxxxxx.com from 192.168.1.34 <30>dnsmasq[14692]: 14998 192.168.1.34/42242 config play.google.com.home.xxxxxx.com is NXDOMAIN <30>dnsmasq[14692]: 14999 192.168.1.34/33479 query[A] play.google.com from 192.168.1.34 <30>dnsmasq[14692]: 14999 192.168.1.34/33479 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 15000 fe80::2##:##ff:fe##:##2f%br0/50437 query[A] play.google.com from fe80::2##:##ff:fe##:##2f <30>dnsmasq[14692]: 15000 fe80::2##:##ff:fe##:##2f%br0/50437 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 15001 192.168.1.34/55938 query[A] play.google.com from 192.168.1.34 <30>dnsmasq[14692]: 15001 192.168.1.34/55938 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 15002 fe80::2##:##ff:fe##:##2f%br0/47805 query[A] play.google.com from fe80::2##:##ff:fe##:##2f <30>dnsmasq[14692]: 15002 fe80::2##:##ff:fe##:##2f%br0/47805 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 15003 192.168.1.34/50070 query[A] play.google.com.home.xxxxxx.com from 192.168.1.34 <30>dnsmasq[14692]: 15003 192.168.1.34/50070 config play.google.com.home.xxxxxx.com is NXDOMAIN <30>dnsmasq[14692]: 15004 fe80::2##:##ff:fe##:##2f%br0/32959 query[A] clients4.google.com from fe80::2##:##ff:fe##:##2f <30>dnsmasq[14692]: 15004 fe80::2##:##ff:fe##:##2f%br0/32959 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 15005 192.168.1.34/48257 query[A] clients4.google.com from 192.168.1.34 <30>dnsmasq[14692]: 15005 192.168.1.34/48257 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 15006 fe80::2##:##ff:fe##:##2f%br0/48850 query[A] clients4.google.com from fe80::2##:##ff:fe##:##2f <30>dnsmasq[14692]: 15006 fe80::2##:##ff:fe##:##2f%br0/48850 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 15007 192.168.1.34/52586 query[A] clients4.google.com.home.xxxxxx.com from 192.168.1.34 <30>dnsmasq[14692]: 15007 192.168.1.34/52586 config clients4.google.com.home.xxxxxx.com is NXDOMAIN <30>dnsmasq[14692]: 15008 192.168.1.34/40599 query[A] clients4.google.com from 192.168.1.34 <30>dnsmasq[14692]: 15008 192.168.1.34/40599 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 15009 fe80::2##:##ff:fe##:##2f%br0/41284 query[A] clients4.google.com from fe80::2##:##ff:fe##:##2f <30>dnsmasq[14692]: 15009 fe80::2##:##ff:fe##:##2f%br0/41284 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 15010 192.168.1.34/42139 query[A] clients4.google.com from 192.168.1.34 <30>dnsmasq[14692]: 15010 192.168.1.34/42139 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 15011 fe80::2##:##ff:fe##:##2f%br0/58355 query[A] clients4.google.com from fe80::2##:##ff:fe##:##2f <30>dnsmasq[14692]: 15011 fe80::2##:##ff:fe##:##2f%br0/58355 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 15012 192.168.1.34/33851 query[A] clients4.google.com.home.xxxxxx.com from 192.168.1.34 <30>dnsmasq[14692]: 15012 192.168.1.34/33851 config clients4.google.com.home.xxxxxx.com is NXDOMAIN <31>dhcpcd[82]: eth0: ARP probing 192.168.100.10 (3 of 3), next in 2.0 seconds <30>dnsmasq[14692]: 15013 192.168.1.58/49401 query[A] push.prod.netflix.com from 192.168.1.58 <30>dnsmasq[14692]: 15013 192.168.1.58/49401 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 15014 192.168.1.58/49401 query[A] api-global.netflix.com from 192.168.1.58 <30>dnsmasq[14692]: 15014 192.168.1.58/49401 config error is REFUSED (EDE: network error) 
<31>dhcpcd[82]: eth0: sending Router Solicitation
<30>dnsmasq[14692]: 15015 fe80::2##:##ff:fe##:##2f%br0/47240 query[A] ssl.gstatic.com from fe80::2##:##ff:fe##:##2f <30>dnsmasq[14692]: 15015 fe80::2##:##ff:fe##:##2f%br0/47240 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 15016 fe80::2##:##ff:fe##:##2f%br0/47240 query[AAAA] ssl.gstatic.com from fe80::2##:##ff:fe##:##2f <30>dnsmasq[14692]: 15016 fe80::2##:##ff:fe##:##2f%br0/47240 config error is REFUSED (EDE: network error) <30>dnsmasq[14692]: 15017 192.168.1.34/48441 query[A] ssl.gstatic.com.home.xxxxxx.com from 192.168.1.34 <30>dnsmasq[14692]: 15017 192.168.1.34/48441 config ssl.gstatic.com.home.xxxxxx.com is NXDOMAIN <30>dnsmasq[14692]: 15018 192.168.1.34/48441 query[AAAA] ssl.gstatic.com.home.xxxxxx.com from 192.168.1.34 <30>dnsmasq[14692]: 15018 192.168.1.34/48441 config ssl.gstatic.com.home.xxxxxx.com is NXDOMAIN <31>dhcpcd[82]: eth0: broadcasting REBIND6 (xid 0x89df3b), next in 4.1 seconds 
<31>dhcpcd[82]: eth0: DAD completed for 192.168.100.10
<30>dhcpcd[82]: eth0: leased 192.168.100.10 for 20 seconds
<31>dhcpcd[82]: eth0: renew in 10 seconds, rebind in 17 seconds
<31>dhcpcd[82]: eth0: writing lease: /var/db/dhcpcd/eth0.lease
<31>dhcpcd[82]: eth0: adding IP address 192.168.100.10/24 broadcast 192.168.100.255 
<30>dhcpcd[82]: eth0: adding route to 192.168.100.0/24
<30>dhcpcd[82]: eth0: adding default route via 192.168.100.1
<31>dhcpcd[82]: eth0: ARP announcing 192.168.100.10 (1 of 2), next in 2.0 seconds 
<31>dhcpcd[82]: eth0: executing: /etc/dhcpcd.sh BOUND
<13>dhcp: Upstream routers: 192.168.100.1
<13>dhcp: Domain name: (none)
<13>dhcp: DNS servers: (none)
<28>dnsmasq[14692]: no servers found in /tmp/resolv.conf, will retry
<13>dhcp: NTP servers: (none)
<30>dnsmasq[14692]: 15019 127.0.0.1/50561 query[A] pool.ntp.org from 127.0.0.1 <75>ntpclient[28632]: Failed resolving address to hostname pool.ntp.org: No address associated with hostname 
<75>ntpclient[28632]: Unable lookup pool.ntp.org
<31>dhcpcd[82]: eth0: ARP announcing 192.168.100.10 (2 of 2)
<31>dhcpcd[82]: eth0: sending Router Solicitation
<31>dhcpcd[82]: eth0: broadcasting REBIND6 (xid 0x89df3b), next in 4.2 seconds 
<27>dhcpcd[82]: eth0: failed to rebind prior DHCPv6 delegation
<31>dhcpcd[82]: eth0: executing: /etc/dhcpcd.sh EXPIRE6
<31>dhcpcd[82]: eth0: soliciting a DHCPv6 lease
<31>dhcpcd[82]: eth0: delaying SOLICIT6 (xid 0x023fe5), next in 1.0 seconds
<31>dhcpcd[82]: eth0: renewing lease of 192.168.100.10
<31>dhcpcd[82]: eth0: sending REQUEST (xid 0x7e7a37a6), next in 4.9 seconds
<31>dhcpcd[82]: eth0: acknowledged 192.168.100.10 from 192.168.100.1
<31>dhcpcd[82]: eth0: leased 192.168.100.10 for 20 seconds
<31>dhcpcd[82]: eth0: renew in 10 seconds, rebind in 17 seconds
<31>dhcpcd[82]: eth0: writing lease: /var/db/dhcpcd/eth0.lease
<31>dhcpcd[82]: eth0: adding IP address 192.168.100.10/24 broadcast 192.168.100.255 <31>dhcpcd[82]: eth0: ARP announcing 192.168.100.10 (1 of 2), next in 2.0 seconds 
<31>dhcpcd[82]: eth0: executing: /etc/dhcpcd.sh RENEW
<13>dhcp: NTP servers: (none)
<75>ntpclient[28638]: Failed resolving address to hostname pool.ntp.org: No address associated with hostname 
<75>ntpclient[28638]: Unable lookup pool.ntp.org
<31>dhcpcd[82]: eth0: broadcasting SOLICIT6 (xid 0x023fe5), next in 1.1 seconds 
<31>dhcpcd[82]: eth0: ARP announcing 192.168.100.10 (2 of 2)
<31>dhcpcd[82]: eth0: sending Router Solicitation
<28>dhcpcd[82]: eth0: no IPv6 Routers available
<31>dhcpcd[82]: eth0: renewing lease of 192.168.100.10
<31>dhcpcd[82]: eth0: sending REQUEST (xid 0xad089a2), next in 4.2 seconds
<31>dhcpcd[82]: eth0: acknowledged 192.168.100.10 from 192.168.100.1
<31>dhcpcd[82]: eth0: leased 192.168.100.10 for 20 seconds
<31>dhcpcd[82]: eth0: renew in 10 seconds, rebind in 17 seconds
<31>dhcpcd[82]: eth0: writing lease: /var/db/dhcpcd/eth0.lease
<31>dhcpcd[82]: eth0: adding IP address 192.168.100.10/24 broadcast 192.168.100.255 <31>dhcpcd[82]: eth0: ARP announcing 192.168.100.10 (1 of 2), next in 2.0 seconds 
<31>dhcpcd[82]: eth0: executing: /etc/dhcpcd.sh RENEW
<13>dhcp: NTP servers: (none)
<75>ntpclient[28642]: Failed resolving address to hostname pool.ntp.org: No address associated with hostname 
<75>ntpclient[28642]: Unable lookup pool.ntp.org
<31>dhcpcd[82]: eth0: broadcasting SOLICIT6 (xid 0x023fe5), next in 2.1 seconds 
<31>dhcpcd[82]: eth0: ARP announcing 192.168.100.10 (2 of 2)
<31>dhcpcd[82]: eth0: renewing lease of 192.168.100.10
<31>dhcpcd[82]: eth0: sending REQUEST (xid 0x174dce00), next in 4.9 seconds
<31>dhcpcd[82]: eth0: acknowledged 192.168.100.10 from 192.168.100.1
<31>dhcpcd[82]: eth0: leased 192.168.100.10 for 20 seconds
<31>dhcpcd[82]: eth0: renew in 10 seconds, rebind in 17 seconds
<31>dhcpcd[82]: eth0: writing lease: /var/db/dhcpcd/eth0.lease
<31>dhcpcd[82]: eth0: adding IP address 192.168.100.10/24 broadcast 192.168.100.255 <31>dhcpcd[82]: eth0: ARP announcing 192.168.100.10 (1 of 2), next in 2.0 seconds 
<31>dhcpcd[82]: eth0: executing: /etc/dhcpcd.sh RENEW
<13>dhcp: NTP servers: (none)
<75>ntpclient[28646]: Failed resolving address to hostname pool.ntp.org: No address associated with hostname 
<75>ntpclient[28646]: Unable lookup pool.ntp.org
<31>dhcpcd[82]: eth0: ARP announcing 192.168.100.10 (2 of 2)
<31>dhcpcd[82]: eth0: broadcasting SOLICIT6 (xid 0x023fe5), next in 4.1 seconds 
<31>dhcpcd[82]: eth0: renewing lease of 192.168.100.10
<31>dhcpcd[82]: eth0: sending REQUEST (xid 0xe4a3113f), next in 3.1 seconds
<31>dhcpcd[82]: eth0: acknowledged 192.168.100.10 from 192.168.100.1
<31>dhcpcd[82]: eth0: leased 192.168.100.10 for 20 seconds
<31>dhcpcd[82]: eth0: renew in 10 seconds, rebind in 17 seconds
<31>dhcpcd[82]: eth0: writing lease: /var/db/dhcpcd/eth0.lease
<31>dhcpcd[82]: eth0: adding IP address 192.168.100.10/24 broadcast 192.168.100.255 <31>dhcpcd[82]: eth0: ARP announcing 192.168.100.10 (1 of 2), next in 2.0 seconds 
<31>dhcpcd[82]: eth0: executing: /etc/dhcpcd.sh RENEW
<13>dhcp: NTP servers: (none)
<75>ntpclient[28650]: Failed resolving address to hostname pool.ntp.org: No address associated with hostname 
<75>ntpclient[28650]: Unable lookup pool.ntp.org
<31>dhcpcd[82]: eth0: ARP announcing 192.168.100.10 (2 of 2)
<31>dhcpcd[82]: eth0: broadcasting SOLICIT6 (xid 0x023fe5), next in 7.7 seconds 
<31>dhcpcd[82]: eth0: renewing lease of 192.168.100.10
<31>dhcpcd[82]: eth0: sending REQUEST (xid 0x41bcc168), next in 4.7 seconds
<31>dhcpcd[82]: eth0: acknowledged 192.168.100.10 from 192.168.100.1
<31>dhcpcd[82]: eth0: leased 192.168.100.10 for 20 seconds
<31>dhcpcd[82]: eth0: renew in 10 seconds, rebind in 17 seconds
<31>dhcpcd[82]: eth0: writing lease: /var/db/dhcpcd/eth0.lease
<31>dhcpcd[82]: eth0: adding IP address 192.168.100.10/24 broadcast 192.168.100.255 <31>dhcpcd[82]: eth0: ARP announcing 192.168.100.10 (1 of 2), next in 2.0 seconds 
<31>dhcpcd[82]: eth0: executing: /etc/dhcpcd.sh RENEW
<13>dhcp: NTP servers: (none)
<75>ntpclient[28654]: Failed resolving address to hostname pool.ntp.org: No address associated with hostname 
<75>ntpclient[28654]: Unable lookup pool.ntp.org
<31>dhcpcd[82]: eth0: ARP announcing 192.168.100.10 (2 of 2)
<31>dhcpcd[82]: eth0: broadcasting SOLICIT6 (xid 0x023fe5), next in 14.7 seconds 
<31>dhcpcd[82]: eth0: renewing lease of 192.168.100.10
<31>dhcpcd[82]: eth0: sending REQUEST (xid 0xe230830), next in 3.9 seconds
<31>dhcpcd[82]: eth0: acknowledged 192.168.100.10 from 192.168.100.1
<31>dhcpcd[82]: eth0: leased 192.168.100.10 for 20 seconds
<31>dhcpcd[82]: eth0: renew in 10 seconds, rebind in 17 seconds
<31>dhcpcd[82]: eth0: writing lease: /var/db/dhcpcd/eth0.lease
<31>dhcpcd[82]: eth0: adding IP address 192.168.100.10/24 broadcast 192.168.100.255 <31>dhcpcd[82]: eth0: ARP announcing 192.168.100.10 (1 of 2), next in 2.0 seconds 
<31>dhcpcd[82]: eth0: executing: /etc/dhcpcd.sh RENEW
<13>dhcp: NTP servers: (none)
<75>ntpclient[28658]: Failed resolving address to hostname pool.ntp.org: No address associated with hostname 
<75>ntpclient[28658]: Unable lookup pool.ntp.org
<31>dhcpcd[82]: eth0: ARP announcing 192.168.100.10 (2 of 2)
<31>dhcpcd[82]: eth0: renewing lease of 192.168.100.10
<31>dhcpcd[82]: eth0: sending REQUEST (xid 0xd9a672e3), next in 3.4 seconds
<31>dhcpcd[82]: eth0: acknowledged 192.168.100.10 from 192.168.100.1
<31>dhcpcd[82]: eth0: leased 192.168.100.10 for 20 seconds
<31>dhcpcd[82]: eth0: renew in 10 seconds, rebind in 17 seconds
<31>dhcpcd[82]: eth0: writing lease: /var/db/dhcpcd/eth0.lease
<31>dhcpcd[82]: eth0: adding IP address 192.168.100.10/24 broadcast 192.168.100.255 <31>dhcpcd[82]: eth0: ARP announcing 192.168.100.10 (1 of 2), next in 2.0 seconds 
<31>dhcpcd[82]: eth0: executing: /etc/dhcpcd.sh RENEW
<13>dhcp: NTP servers: (none)
<75>ntpclient[28662]: Failed resolving address to hostname pool.ntp.org: No address associated with hostname 
<75>ntpclient[28662]: Unable lookup pool.ntp.org
<31>dhcpcd[82]: eth0: ARP announcing 192.168.100.10 (2 of 2)
<31>dhcpcd[82]: eth0: broadcasting SOLICIT6 (xid 0x023fe5), next in 31.6 seconds 
<31>dhcpcd[82]: eth0: renewing lease of 192.168.100.10
<31>dhcpcd[82]: eth0: sending REQUEST (xid 0xdfa6a085), next in 3.3 seconds
<30>dhcpcd[82]: eth0: ADV 2601:####:####:####::/64 from 2001:558:4023:8b::10 <31>dhcpcd[82]: eth0: broadcasting REQUEST6 (xid 0x496b13), next in 1.0 seconds 
<30>dhcpcd[82]: eth0: REPLY6 received from 2001:558:4023:8b::10
<30>dhcpcd[82]: eth0: renew in 99983, rebind in 203663, expire in 272783 seconds 
<30>dhcpcd[82]: lo: adding reject route to 2601:####:####:####::/64
<31>dhcpcd[82]: eth0: writing lease: /var/db/dhcpcd/eth0.lease6
<30>dhcpcd[82]: eth0: delegated prefix 2601:####:####:####::/64
<30>dhcpcd[82]: br0: adding address 2601:####:####:####::1/64
<31>dhcpcd[82]: br0: pltime 272783 seconds, vltime 272783 seconds
<31>dhcpcd[82]: br0: waiting for DHCPv6 DAD to complete
<30>dhcpcd[82]: br0: adding route to 2601:####:####:####::/64
<30>dhcpcd[82]: lo: deleting reject route to 2601:####:####:####::/64
<31>dhcpcd[82]: eth0: executing: /etc/dhcpcd.sh BOUND6
<31>dhcpcd[82]: br0: DHCPv6 DAD completed
<31>dhcpcd[82]: br0: executing: /etc/dhcpcd.sh DELEGATED6
<31>dhcpcd[82]: eth0: sending REQUEST (xid 0xdfa6a085), next in 8.3 seconds
<28>dhcpcd[82]: eth0: failed to renew DHCP, rebinding
<31>dhcpcd[82]: eth0: expire in 3 seconds
<31>dhcpcd[82]: eth0: sending REQUEST (xid 0xdfa6a085), next in 3.2 seconds
<28>dhcpcd[82]: eth0: NAK: from 96.113.192.209
<31>dhcpcd[82]: eth0: deleting IP address 192.168.100.10/24
<30>dhcpcd[82]: eth0: deleting route to 192.168.100.0/24
<30>dhcpcd[82]: eth0: deleting default route via 192.168.100.1
<31>dhcpcd[82]: eth0: executing: /etc/dhcpcd.sh NAK
<30>dhcpcd[82]: eth0: soliciting a DHCP lease (requesting 73.###.###.56)
<31>dhcpcd[82]: eth0: sending DISCOVER (xid 0x423103f5), next in 3.9 seconds 
<30>dhcpcd[82]: eth0: offered 73.###.###.246 from 96.113.192.209
<31>dhcpcd[82]: eth0: sending REQUEST (xid 0x423103f5), next in 4.0 seconds
<31>dhcpcd[82]: eth0: acknowledged 73.###.###.246 from 96.113.192.209
<30>dhcpcd[82]: eth0: probing address 73.###.###.246/23
<31>dhcpcd[82]: eth0: probing for 73.###.###.246
<31>dhcpcd[82]: eth0: ARP probing 73.###.###.246 (1 of 3), next in 1.5 seconds <31>dhcpcd[82]: eth0: ARP probing 73.###.###.246 (2 of 3), next in 1.4 seconds <31>dhcpcd[82]: eth0: ARP probing 73.###.###.246 (3 of 3), next in 2.0 seconds 
<31>dhcpcd[82]: eth0: DAD completed for 73.###.###.246
<30>dhcpcd[82]: eth0: leased 73.###.###.246 for 229746 seconds
<31>dhcpcd[82]: eth0: renew in 56946 seconds, rebind in 186546 seconds
<31>dhcpcd[82]: eth0: writing lease: /var/db/dhcpcd/eth0.lease
<31>dhcpcd[82]: eth0: adding IP address 73.###.###.246/23 broadcast 255.255.255.255 
<30>dhcpcd[82]: eth0: adding route to 73.###.###.0/23
<30>dhcpcd[82]: eth0: adding default route via 73.###.###.1
<31>dhcpcd[82]: eth0: ARP announcing 73.###.###.246 (1 of 2), next in 2.0 seconds 
<31>dhcpcd[82]: eth0: executing: /etc/dhcpcd.sh BOUND
<13>dhcp: Upstream routers: 73.###.###.1
<13>dhcp: Domain name: hsd1.nh.comcast.net.
<13>dhcp: DNS servers: 75.75.75.75 75.75.76.76
<13>dhcp: NTP servers: (none)
<75>ntpclient[28674]: Failed resolving address to hostname pool.ntp.org: No address associated with hostname 
<75>ntpclient[28674]: Unable lookup pool.ntp.org
<31>dhcpcd[82]: eth0: ARP announcing 73.###.###.246 (2 of 2)
<30>dhcpcd[82]: eth0: Router Advertisement from fe80::dead:beef:1:1
<30>dhcpcd[82]: eth0: adding default route via fe80::dead:beef:1:1
<31>dhcpcd[82]: eth0: executing: /etc/dhcpcd.sh ROUTERADVERT


_______________________________________________
Dnsmasq-discuss mailing list
Dnsmasq-discuss@lists.thekelleys.org.uk
https://lists.thekelleys.org.uk/cgi-bin/mailman/listinfo/dnsmasq-discuss

Reply via email to