Aaron Brice
2017-01-23 21:47:35 UTC
I am testing a bluetooth networking (bnep0) device on an Ubuntu 16.04
laptop. Everything works fine if I comment out dns=dnsmasq from the
NetworkManager.conf. With dnsmasq on, everything works fine the first
time I connect my bluetooth network device. When I disconnect it and
reconnect it, DNS lookups fail immediately with a REFUSED status. When
I restart the NetworkManager service everything works again.
I turned on log-queries, and it shows the query is received, but no
response is shown in the logs and also no error messages. The logs show
that on the reconnect the DHCP succeeded, and dnsmasq received the
upstream nameservers from the DHCP response, but tcpdump does not show
any DNS traffic to those nameservers when I use dig. Is there a
verbosity setting that might show some more information on why the DNS
queries are being refused?
Logs:
Jan 18 16:56:02 datasoft-travel dnsmasq[7973]: setting upstream servers
from DBus
Jan 18 16:56:02 datasoft-travel dnsmasq[7973]: using nameserver
192.168.10.2#53(via bnep0)
Jan 18 16:56:02 datasoft-travel dnsmasq[7973]: using nameserver
68.105.29.16#53(via bnep0)
Jan 18 16:56:02 datasoft-travel dnsmasq[7973]: using nameserver
68.105.28.16#53(via bnep0)
Jan 18 16:56:02 datasoft-travel dnsmasq[7973]: query[A] daisy.ubuntu.com
from 127.0.0.1
Jan 18 16:56:02 datasoft-travel whoopsie[883]: [16:56:02] Cannot reach:
https://daisy.ubuntu.com
Jan 18 16:56:02 datasoft-travel dnsmasq[7973]: query[SOA] local from
127.0.0.1
Jan 18 16:56:02 datasoft-travel NetworkManager[7926]: <info>
[1484783762.2180] device (5C:31:3E:EC:71:B3): Activation: successful,
device activated.
Jan 18 16:56:02 datasoft-travel nm-dispatcher: req:2 'up' [bnep0]: new
request (1 scripts)
Jan 18 16:56:02 datasoft-travel nm-dispatcher: req:2 'up' [bnep0]: start
running ordered scripts...
Jan 18 16:56:02 datasoft-travel whoopsie[883]: [16:56:02] The default
IPv4 route is: /org/freedesktop/NetworkManager/ActiveConnection/2
Jan 18 16:56:02 datasoft-travel deja-dup-monito[3334]: Source ID 948 was
not found when attempting to remove it
Jan 18 16:56:02 datasoft-travel whoopsie[883]: [16:56:02] Network
connection may be a paid data plan:
/org/freedesktop/NetworkManager/Devices/3
Jan 18 16:56:02 datasoft-travel dnsmasq[7973]: query[A] daisy.ubuntu.com
from 127.0.0.1
Jan 18 16:56:02 datasoft-travel whoopsie[883]: [16:56:02] Cannot reach:
https://daisy.ubuntu.com
Jan 18 16:56:02 datasoft-travel dnsmasq[7973]: query[SOA] local from
127.0.0.1
Jan 18 16:56:02 datasoft-travel systemd[1]: Reloading.
Jan 18 16:56:02 datasoft-travel systemd[1]: snapd.refresh.timer: Adding
4h 43min 19.514615s random time.
Jan 18 16:56:02 datasoft-travel systemd[1]: apt-daily.timer: Adding 2h
3min 35.943181s random time.
Jan 18 16:56:02 datasoft-travel dnsmasq[7973]: query[SOA] local from
127.0.0.1
Jan 18 16:56:02 datasoft-travel systemd[1]: Reloading.
Jan 18 16:56:02 datasoft-travel systemd[1]: snapd.refresh.timer: Adding
1h 9min 9.603579s random time.
Jan 18 16:56:02 datasoft-travel systemd[1]: apt-daily.timer: Adding 6h
19min 48.788489s random time.
Jan 18 16:56:03 datasoft-travel dnsmasq[7973]: query[A]
fsodqcsvqrxnlyy.corp.datasoft.com from 127.0.0.1
Jan 18 16:56:03 datasoft-travel dnsmasq[7973]: query[A]
xasqjyv.corp.datasoft.com from 127.0.0.1
Jan 18 16:56:03 datasoft-travel dnsmasq[7973]: query[A]
ftmkaipfpi.corp.datasoft.com from 127.0.0.1
Jan 18 16:56:03 datasoft-travel dnsmasq[7973]: query[A] daisy.ubuntu.com
from 127.0.0.1
Jan 18 16:56:03 datasoft-travel whoopsie[883]: [16:56:03] Cannot reach:
https://daisy.ubuntu.com
Jan 18 16:56:03 datasoft-travel avahi-daemon[903]: Joining mDNS
multicast group on interface bnep0.IPv6 with address
fe80::cf57:5122:c1f0:43cd.
Jan 18 16:56:03 datasoft-travel avahi-daemon[903]: New relevant
interface bnep0.IPv6 for mDNS.
Jan 18 16:56:03 datasoft-travel avahi-daemon[903]: Registering new
address record for fe80::cf57:5122:c1f0:43cd on bnep0.*.
Jan 18 16:56:03 datasoft-travel dnsmasq[7973]: query[A] daisy.ubuntu.com
from 127.0.0.1
Jan 18 16:56:03 datasoft-travel whoopsie[883]: [16:56:03] Cannot reach:
https://daisy.ubuntu.com
Jan 18 16:56:03 datasoft-travel NetworkManager[7926]: <info>
[1484783763.4104] policy: set 'SIDEBRIDGE_002020 Network' (bnep0) as
default for IPv6 routing and DNS
Jan 18 16:56:03 datasoft-travel dnsmasq[7973]: query[A] daisy.ubuntu.com
from 127.0.0.1
Jan 18 16:56:03 datasoft-travel whoopsie[883]: [16:56:03] Cannot reach:
https://daisy.ubuntu.com
Jan 18 16:56:04 datasoft-travel dnsmasq[7973]: query[A]
hmtddsov.corp.datasoft.com from 127.0.0.1
Jan 18 16:56:04 datasoft-travel dnsmasq[7973]: query[A]
bigsooxchvptr.corp.datasoft.com from 127.0.0.1
Jan 18 16:56:04 datasoft-travel dnsmasq[7973]: query[A]
ecbamrdeizxzc.corp.datasoft.com from 127.0.0.1
Jan 18 16:56:04 datasoft-travel dnsmasq[7973]: query[A] daisy.ubuntu.com
from 127.0.0.1
Jan 18 16:56:04 datasoft-travel whoopsie[883]: [16:56:04] Cannot reach:
https://daisy.ubuntu.com
Jan 18 16:56:04 datasoft-travel ModemManager[898]: <info> Couldn't check
support for device at '/sys/devices/pci0000:00/0000:00:14.0/usb1/1-5':
not supported by any plugin
Jan 18 16:56:05 datasoft-travel dnsmasq[7973]: query[A] www.google.com
from 127.0.0.1
Jan 18 16:56:06 datasoft-travel dnsmasq[7973]: query[A] www.google.com
from 127.0.0.1
Jan 18 16:56:07 datasoft-travel dnsmasq[7973]: query[A] www.cnn.com from
127.0.0.1
Thanks,
Aaron
laptop. Everything works fine if I comment out dns=dnsmasq from the
NetworkManager.conf. With dnsmasq on, everything works fine the first
time I connect my bluetooth network device. When I disconnect it and
reconnect it, DNS lookups fail immediately with a REFUSED status. When
I restart the NetworkManager service everything works again.
I turned on log-queries, and it shows the query is received, but no
response is shown in the logs and also no error messages. The logs show
that on the reconnect the DHCP succeeded, and dnsmasq received the
upstream nameservers from the DHCP response, but tcpdump does not show
any DNS traffic to those nameservers when I use dig. Is there a
verbosity setting that might show some more information on why the DNS
queries are being refused?
Logs:
Jan 18 16:56:02 datasoft-travel dnsmasq[7973]: setting upstream servers
from DBus
Jan 18 16:56:02 datasoft-travel dnsmasq[7973]: using nameserver
192.168.10.2#53(via bnep0)
Jan 18 16:56:02 datasoft-travel dnsmasq[7973]: using nameserver
68.105.29.16#53(via bnep0)
Jan 18 16:56:02 datasoft-travel dnsmasq[7973]: using nameserver
68.105.28.16#53(via bnep0)
Jan 18 16:56:02 datasoft-travel dnsmasq[7973]: query[A] daisy.ubuntu.com
from 127.0.0.1
Jan 18 16:56:02 datasoft-travel whoopsie[883]: [16:56:02] Cannot reach:
https://daisy.ubuntu.com
Jan 18 16:56:02 datasoft-travel dnsmasq[7973]: query[SOA] local from
127.0.0.1
Jan 18 16:56:02 datasoft-travel NetworkManager[7926]: <info>
[1484783762.2180] device (5C:31:3E:EC:71:B3): Activation: successful,
device activated.
Jan 18 16:56:02 datasoft-travel nm-dispatcher: req:2 'up' [bnep0]: new
request (1 scripts)
Jan 18 16:56:02 datasoft-travel nm-dispatcher: req:2 'up' [bnep0]: start
running ordered scripts...
Jan 18 16:56:02 datasoft-travel whoopsie[883]: [16:56:02] The default
IPv4 route is: /org/freedesktop/NetworkManager/ActiveConnection/2
Jan 18 16:56:02 datasoft-travel deja-dup-monito[3334]: Source ID 948 was
not found when attempting to remove it
Jan 18 16:56:02 datasoft-travel whoopsie[883]: [16:56:02] Network
connection may be a paid data plan:
/org/freedesktop/NetworkManager/Devices/3
Jan 18 16:56:02 datasoft-travel dnsmasq[7973]: query[A] daisy.ubuntu.com
from 127.0.0.1
Jan 18 16:56:02 datasoft-travel whoopsie[883]: [16:56:02] Cannot reach:
https://daisy.ubuntu.com
Jan 18 16:56:02 datasoft-travel dnsmasq[7973]: query[SOA] local from
127.0.0.1
Jan 18 16:56:02 datasoft-travel systemd[1]: Reloading.
Jan 18 16:56:02 datasoft-travel systemd[1]: snapd.refresh.timer: Adding
4h 43min 19.514615s random time.
Jan 18 16:56:02 datasoft-travel systemd[1]: apt-daily.timer: Adding 2h
3min 35.943181s random time.
Jan 18 16:56:02 datasoft-travel dnsmasq[7973]: query[SOA] local from
127.0.0.1
Jan 18 16:56:02 datasoft-travel systemd[1]: Reloading.
Jan 18 16:56:02 datasoft-travel systemd[1]: snapd.refresh.timer: Adding
1h 9min 9.603579s random time.
Jan 18 16:56:02 datasoft-travel systemd[1]: apt-daily.timer: Adding 6h
19min 48.788489s random time.
Jan 18 16:56:03 datasoft-travel dnsmasq[7973]: query[A]
fsodqcsvqrxnlyy.corp.datasoft.com from 127.0.0.1
Jan 18 16:56:03 datasoft-travel dnsmasq[7973]: query[A]
xasqjyv.corp.datasoft.com from 127.0.0.1
Jan 18 16:56:03 datasoft-travel dnsmasq[7973]: query[A]
ftmkaipfpi.corp.datasoft.com from 127.0.0.1
Jan 18 16:56:03 datasoft-travel dnsmasq[7973]: query[A] daisy.ubuntu.com
from 127.0.0.1
Jan 18 16:56:03 datasoft-travel whoopsie[883]: [16:56:03] Cannot reach:
https://daisy.ubuntu.com
Jan 18 16:56:03 datasoft-travel avahi-daemon[903]: Joining mDNS
multicast group on interface bnep0.IPv6 with address
fe80::cf57:5122:c1f0:43cd.
Jan 18 16:56:03 datasoft-travel avahi-daemon[903]: New relevant
interface bnep0.IPv6 for mDNS.
Jan 18 16:56:03 datasoft-travel avahi-daemon[903]: Registering new
address record for fe80::cf57:5122:c1f0:43cd on bnep0.*.
Jan 18 16:56:03 datasoft-travel dnsmasq[7973]: query[A] daisy.ubuntu.com
from 127.0.0.1
Jan 18 16:56:03 datasoft-travel whoopsie[883]: [16:56:03] Cannot reach:
https://daisy.ubuntu.com
Jan 18 16:56:03 datasoft-travel NetworkManager[7926]: <info>
[1484783763.4104] policy: set 'SIDEBRIDGE_002020 Network' (bnep0) as
default for IPv6 routing and DNS
Jan 18 16:56:03 datasoft-travel dnsmasq[7973]: query[A] daisy.ubuntu.com
from 127.0.0.1
Jan 18 16:56:03 datasoft-travel whoopsie[883]: [16:56:03] Cannot reach:
https://daisy.ubuntu.com
Jan 18 16:56:04 datasoft-travel dnsmasq[7973]: query[A]
hmtddsov.corp.datasoft.com from 127.0.0.1
Jan 18 16:56:04 datasoft-travel dnsmasq[7973]: query[A]
bigsooxchvptr.corp.datasoft.com from 127.0.0.1
Jan 18 16:56:04 datasoft-travel dnsmasq[7973]: query[A]
ecbamrdeizxzc.corp.datasoft.com from 127.0.0.1
Jan 18 16:56:04 datasoft-travel dnsmasq[7973]: query[A] daisy.ubuntu.com
from 127.0.0.1
Jan 18 16:56:04 datasoft-travel whoopsie[883]: [16:56:04] Cannot reach:
https://daisy.ubuntu.com
Jan 18 16:56:04 datasoft-travel ModemManager[898]: <info> Couldn't check
support for device at '/sys/devices/pci0000:00/0000:00:14.0/usb1/1-5':
not supported by any plugin
Jan 18 16:56:05 datasoft-travel dnsmasq[7973]: query[A] www.google.com
from 127.0.0.1
Jan 18 16:56:06 datasoft-travel dnsmasq[7973]: query[A] www.google.com
from 127.0.0.1
Jan 18 16:56:07 datasoft-travel dnsmasq[7973]: query[A] www.cnn.com from
127.0.0.1
Thanks,
Aaron