[Dnsmasq-discuss] Leopard oddity or me?

B. Cook bcook at poughkeepsieschools.org
Thu Jan 31 13:44:42 GMT 2008


Hello all,

we are a PC/Mac school and since I have upgraded to leopard I see  
delays in things that I never saw before.  Windows 2k/XP boxes do not  
have this 'delay'..  We have about 20+ FreeBSD machines that we have  
around here and that 2-4 second delay logging in really is starting to  
get annoying.  Students don't really see anything wrong as they mostly  
web browse.

below is an excerpt from the debug of dnsmasq 2.40.

(some background information: dnsmasq is running on machine bacula,  
which is FreeBSD 6.2-pwhatever, mbookpro is a macbook pro running  
leopard upgraded from tiger.  What you are looking at is me restarting  
dnsmasq while ssh'd into bacula.  Then from mbookpro I have opened a  
new terminal tab and typed ssh bacula below is what was going on at  
that time.  mbookpro is 192.168.10.178, bacula is 192.168.10.198. )

Jan 31 08:08:54 <local7.info> bacula dnsmasq[6607]: reading /usr/local/ 
etc/resolv.conf
Jan 31 08:08:54 <local7.info> bacula dnsmasq[6607]: using nameserver  
127.0.0.1#53
Jan 31 08:08:54 <local7.info> bacula dnsmasq[6607]: using nameserver  
10.20.0.64#53 for domain 20.10.in-addr.arpa
Jan 31 08:08:54 <local7.info> bacula dnsmasq[6607]: using nameserver  
10.20.0.64#53 for domain pcsd
Jan 31 08:08:54 <local7.info> bacula dnsmasq[6607]: using nameserver  
10.20.0.16#53 for domain poughkeepsieschools.org
Jan 31 08:08:54 <local7.info> bacula dnsmasq[6607]: using nameserver  
127.53.0.1#53 for domain tcentral.local
Jan 31 08:08:54 <local7.info> bacula dnsmasq[6607]: using nameserver  
127.53.0.1#53 for domain 10.168.192.in-addr.arpa
Jan 31 08:08:54 <local7.info> bacula dnsmasq[6607]: read /etc/hosts -  
2 addresses
Jan 31 08:08:58 <local7.debug> bacula dnsmasq[6607]: query[PTR]  
184.10.168.192.in-addr.arpa from 192.168.10.184
Jan 31 08:08:58 <local7.debug> bacula dnsmasq[6607]: DHCP  
192.168.10.184 is MacPro.tcentral.local
Jan 31 08:09:12 <local7.debug> bacula dnsmasq[6607]: query[SRV]  
_ssh._tcp.bacula from 192.168.10.178
Jan 31 08:09:12 <local7.debug> bacula dnsmasq[6607]: config  
_ssh._tcp.bacula is NODATA-IPv6
Jan 31 08:09:12 <local7.debug> bacula dnsmasq[6607]: query[SRV]  
_ssh._tcp.bacula.tcentral.local from 192.168.10.178
Jan 31 08:09:12 <local7.debug> bacula dnsmasq[6607]: config  
_ssh._tcp.bacula.tcentral.local is NODATA-IPv6
Jan 31 08:09:12 <local7.debug> bacula dnsmasq[6607]: query[SRV]  
_ssh._tcp.bacula.tcentral.local from 192.168.10.178
Jan 31 08:09:12 <local7.debug> bacula dnsmasq[6607]: config  
_ssh._tcp.bacula.tcentral.local is NODATA-IPv6
Jan 31 08:09:14 <local7.debug> bacula dnsmasq[6607]: query[A]  
bacula.tcentral.local from 192.168.10.178
Jan 31 08:09:14 <local7.debug> bacula dnsmasq[6607]: forwarded  
bacula.tcentral.local to 127.53.0.1
Jan 31 08:09:14 <local7.debug> bacula dnsmasq[6607]: reply  
bacula.tcentral.local is 192.168.10.198
Jan 31 08:09:16 <local7.debug> bacula dnsmasq[6607]: query[PTR]  
178.10.168.192.in-addr.arpa from 192.168.10.198
Jan 31 08:09:16 <local7.debug> bacula dnsmasq[6607]: DHCP  
192.168.10.178 is mbookpro.tcentral.local
Jan 31 08:09:16 <local7.debug> bacula dnsmasq[6607]: query[A]  
mbookpro.tcentral.local from 192.168.10.198
Jan 31 08:09:16 <local7.debug> bacula dnsmasq[6607]: DHCP  
mbookpro.tcentral.local is 192.168.10.178
Jan 31 08:09:16 <local7.debug> bacula dnsmasq[6607]: query[PTR]  
178.10.168.192.in-addr.arpa from 192.168.10.198
Jan 31 08:09:16 <local7.debug> bacula dnsmasq[6607]: DHCP  
192.168.10.178 is mbookpro.tcentral.local
Jan 31 08:09:16 <local7.debug> bacula dnsmasq[6607]: query[A]  
mbookpro.tcentral.local from 192.168.10.198
Jan 31 08:09:16 <local7.debug> bacula dnsmasq[6607]: DHCP  
mbookpro.tcentral.local is 192.168.10.178
Jan 31 08:09:20 <local7.debug> bacula dnsmasq[6607]: query[SRV]  
_ssh._tcp.bacula from 192.168.10.178
Jan 31 08:09:20 <local7.debug> bacula dnsmasq[6607]: config  
_ssh._tcp.bacula is NODATA-IPv6
Jan 31 08:09:20 <local7.debug> bacula dnsmasq[6607]: query[SRV]  
_ssh._tcp.bacula.tcentral.local from 192.168.10.178
Jan 31 08:09:20 <local7.debug> bacula dnsmasq[6607]: config  
_ssh._tcp.bacula.tcentral.local is NODATA-IPv6
Jan 31 08:09:20 <local7.debug> bacula dnsmasq[6607]: query[SRV]  
_ssh._tcp.bacula.tcentral.local from 192.168.10.178
Jan 31 08:09:20 <local7.debug> bacula dnsmasq[6607]: config  
_ssh._tcp.bacula.tcentral.local is NODATA-IPv6
Jan 31 08:09:22 <local7.debug> bacula dnsmasq[6607]: query[A]  
bacula.tcentral.local from 192.168.10.178
Jan 31 08:09:22 <local7.debug> bacula dnsmasq[6607]: cached  
bacula.tcentral.local is 192.168.10.198
Jan 31 08:09:23 <local7.info> bacula dnsmasq[6607]: DHCPINFORM(xl0)  
192.168.10.133 00:18:8b:01:71:ec
Jan 31 08:09:23 <local7.info> bacula dnsmasq[6607]: DHCPACK(xl0)  
192.168.10.133 00:18:8b:01:71:ec register-72cc1e
Jan 31 08:09:24 <local7.debug> bacula dnsmasq[6607]: query[PTR]  
178.10.168.192.in-addr.arpa from 192.168.10.198
Jan 31 08:09:24 <local7.debug> bacula dnsmasq[6607]: DHCP  
192.168.10.178 is mbookpro.tcentral.local
Jan 31 08:09:24 <local7.debug> bacula dnsmasq[6607]: query[A]  
mbookpro.tcentral.local from 192.168.10.198
Jan 31 08:09:24 <local7.debug> bacula dnsmasq[6607]: DHCP  
mbookpro.tcentral.local is 192.168.10.178
Jan 31 08:09:24 <local7.debug> bacula dnsmasq[6607]: query[PTR]  
178.10.168.192.in-addr.arpa from 192.168.10.198
Jan 31 08:09:24 <local7.debug> bacula dnsmasq[6607]: DHCP  
192.168.10.178 is mbookpro.tcentral.local
Jan 31 08:09:24 <local7.debug> bacula dnsmasq[6607]: query[A]  
mbookpro.tcentral.local from 192.168.10.198
Jan 31 08:09:24 <local7.debug> bacula dnsmasq[6607]: DHCP  
mbookpro.tcentral.local is 192.168.10.178
Jan 31 08:09:28 <local7.info> bacula dnsmasq[6607]: DHCPINFORM(xl0)  
192.168.10.133 00:18:8b:01:71:ec
Jan 31 08:09:28 <local7.info> bacula dnsmasq[6607]: DHCPACK(xl0)  
192.168.10.133 00:18:8b:01:71:ec register-72cc1e
Jan 31 08:09:36 <local7.debug> bacula dnsmasq[6607]: query[A]  
finance.yahoo.com from 192.168.10.133
Jan 31 08:09:36 <local7.debug> bacula dnsmasq[6607]: forwarded  
finance.yahoo.com to 127.0.0.1
Jan 31 08:09:36 <local7.debug> bacula dnsmasq[6607]: reply  
finance.yahoo.com is <CNAME>
Jan 31 08:09:36 <local7.debug> bacula dnsmasq[6607]: reply  
finance.yahoo6.akadns.net is 206.190.56.229
Jan 31 08:09:36 <local7.debug> bacula dnsmasq[6607]: query[A]  
finance.yahoo.com from 192.168.10.198
Jan 31 08:09:36 <local7.debug> bacula dnsmasq[6607]: cached  
finance.yahoo.com is <CNAME>
Jan 31 08:09:36 <local7.debug> bacula dnsmasq[6607]: cached  
finance.yahoo6.akadns.net is 206.190.56.229
Jan 31 08:09:36 <local7.debug> bacula dnsmasq[6607]: query[A]  
download.finance.yahoo.com from 192.168.10.198
Jan 31 08:09:36 <local7.debug> bacula dnsmasq[6607]: forwarded  
download.finance.yahoo.com to 127.0.0.1
Jan 31 08:09:36 <local7.debug> bacula dnsmasq[6607]: reply  
download.finance.yahoo.com is <CNAME>
Jan 31 08:09:36 <local7.debug> bacula dnsmasq[6607]: reply  
download.finance.yahoo6.akadns.net is 206.190.56.230
Jan 31 08:09:41 <local7.debug> bacula dnsmasq[6607]: query[SRV]  
_ssh._tcp.bacula from 192.168.10.178
Jan 31 08:09:41 <local7.debug> bacula dnsmasq[6607]: config  
_ssh._tcp.bacula is NODATA-IPv6
Jan 31 08:09:41 <local7.debug> bacula dnsmasq[6607]: query[SRV]  
_ssh._tcp.bacula.tcentral.local from 192.168.10.178
Jan 31 08:09:41 <local7.debug> bacula dnsmasq[6607]: config  
_ssh._tcp.bacula.tcentral.local is NODATA-IPv6
Jan 31 08:09:41 <local7.debug> bacula dnsmasq[6607]: query[SRV]  
_ssh._tcp.bacula.tcentral.local from 192.168.10.178
Jan 31 08:09:41 <local7.debug> bacula dnsmasq[6607]: config  
_ssh._tcp.bacula.tcentral.local is NODATA-IPv6
Jan 31 08:09:43 <local7.debug> bacula dnsmasq[6607]: query[A]  
bacula.tcentral.local from 192.168.10.178
Jan 31 08:09:43 <local7.debug> bacula dnsmasq[6607]: cached  
bacula.tcentral.local is 192.168.10.198
Jan 31 08:09:45 <local7.debug> bacula dnsmasq[6607]: query[PTR]  
178.10.168.192.in-addr.arpa from 192.168.10.198
Jan 31 08:09:45 <local7.debug> bacula dnsmasq[6607]: DHCP  
192.168.10.178 is mbookpro.tcentral.local
Jan 31 08:09:45 <local7.debug> bacula dnsmasq[6607]: query[A]  
mbookpro.tcentral.local from 192.168.10.198
Jan 31 08:09:45 <local7.debug> bacula dnsmasq[6607]: DHCP  
mbookpro.tcentral.local is 192.168.10.178
Jan 31 08:09:45 <local7.debug> bacula dnsmasq[6607]: query[PTR]  
178.10.168.192.in-addr.arpa from 192.168.10.198
Jan 31 08:09:45 <local7.debug> bacula dnsmasq[6607]: DHCP  
192.168.10.178 is mbookpro.tcentral.local
Jan 31 08:09:45 <local7.debug> bacula dnsmasq[6607]: query[A]  
mbookpro.tcentral.local from 192.168.10.198
Jan 31 08:09:45 <local7.debug> bacula dnsmasq[6607]: DHCP  
mbookpro.tcentral.local is 192.168.10.178
^C
root at bacula [/usr/local/etc]# 5 > dnsmasq --version
Dnsmasq version 2.40  Copyright (C) 2000-2007 Simon Kelley
Compile time options no-IPv6 GNU-getopt no-ISC-leasefile no-DBus no- 
I18N TFTP

So at the end of this I see that I do not understand this portion of  
the log:

Jan 31 08:09:12 <local7.debug> bacula dnsmasq[6607]: query[SRV]  
_ssh._tcp.bacula from 192.168.10.178
Jan 31 08:09:12 <local7.debug> bacula dnsmasq[6607]: config  
_ssh._tcp.bacula is NODATA-IPv6
Jan 31 08:09:12 <local7.debug> bacula dnsmasq[6607]: query[SRV]  
_ssh._tcp.bacula.tcentral.local from 192.168.10.178
Jan 31 08:09:12 <local7.debug> bacula dnsmasq[6607]: config  
_ssh._tcp.bacula.tcentral.local is NODATA-IPv6
Jan 31 08:09:12 <local7.debug> bacula dnsmasq[6607]: query[SRV]  
_ssh._tcp.bacula.tcentral.local from 192.168.10.178
Jan 31 08:09:12 <local7.debug> bacula dnsmasq[6607]: config  
_ssh._tcp.bacula.tcentral.local is NODATA-IPv6
Jan 31 08:09:14 <local7.debug> bacula dnsmasq[6607]: query[A]  
bacula.tcentral.local from 192.168.10.178

Does anyone know what this is doing?  I understand the query for the A  
record.. but why did it take 2 seconds for it to get me that answer?   
I don't want to 'disable' something on the mac as I am trying to keep  
the machines as they come, but how can I either answer those SRV  
requests or serve the answer for the ipv4 request sooner?  I have  
tried to disable ipv6 by not building dnsmasq with ipv6 enabled.. but  
it still doesn't seem to make a difference in the time that the A  
record is finally served.

Also why is 192.168.10.198 continually asking for the PTR of the  
connected host?  I am only connected one time.  And that connection is  
sitting idle while I write this message.

Jan 31 08:09:16 <local7.debug> bacula dnsmasq[6607]: query[PTR]  
178.10.168.192.in-addr.arpa from 192.168.10.198
Jan 31 08:09:16 <local7.debug> bacula dnsmasq[6607]: query[PTR]  
178.10.168.192.in-addr.arpa from 192.168.10.198
Jan 31 08:09:24 <local7.debug> bacula dnsmasq[6607]: query[PTR]  
178.10.168.192.in-addr.arpa from 192.168.10.198
Jan 31 08:09:24 <local7.debug> bacula dnsmasq[6607]: query[PTR]  
178.10.168.192.in-addr.arpa from 192.168.10.198
Jan 31 08:09:45 <local7.debug> bacula dnsmasq[6607]: query[PTR]  
178.10.168.192.in-addr.arpa from 192.168.10.198
Jan 31 08:09:45 <local7.debug> bacula dnsmasq[6607]: query[PTR]  
178.10.168.192.in-addr.arpa from 192.168.10.198
Jan 31 08:12:08 <local7.debug> bacula dnsmasq[6607]: query[PTR]  
178.10.168.192.in-addr.arpa from 192.168.10.198
Jan 31 08:12:13 <local7.debug> bacula dnsmasq[6607]: query[PTR]  
178.10.168.192.in-addr.arpa from 192.168.10.198
Jan 31 08:12:13 <local7.debug> bacula dnsmasq[6607]: query[PTR]  
178.10.168.192.in-addr.arpa from 192.168.10.198
Jan 31 08:12:13 <local7.debug> bacula dnsmasq[6607]: query[PTR]  
178.10.168.192.in-addr.arpa from 192.168.10.198
Jan 31 08:12:13 <local7.debug> bacula dnsmasq[6607]: query[PTR]  
178.10.168.192.in-addr.arpa from 192.168.10.198
^C

below is the result of a dig to the authoritative host for the reverse  
zone.

root at bacula [/usr/local/etc]# 16 > dig @127.53.0.1 -x 192.168.10.178

; <<>> DiG 9.3.3 <<>> @127.53.0.1 -x 192.168.10.178
; (1 server found)
;; global options:  printcmd
;; Got answer:
;; ->>HEADER<<- opcode: QUERY, status: NXDOMAIN, id: 695
;; flags: qr aa rd; QUERY: 1, ANSWER: 0, AUTHORITY: 1, ADDITIONAL: 0

;; QUESTION SECTION:
;178.10.168.192.in-addr.arpa.	IN	PTR

;; AUTHORITY SECTION:
10.168.192.in-addr.arpa. 2560	IN	SOA	127.53.0.1.  
hostmaster.tcentral.local. 1199720046 16384 2048 1048576 2560

;; Query time: 1 msec
;; SERVER: 127.53.0.1#53(127.53.0.1)
;; WHEN: Thu Jan 31 08:39:07 2008
;; MSG SIZE  rcvd: 116
^C

Below is the config that I am using for this.

bind-interfaces
cache-size=4096
interface=xl0
dhcp-authoritative
dhcp-range=192.168.10.100,192.168.10.200,3h
dhcp-option=3,192.168.10.1      # set gateway
dhcp-option=42,10.20.0.29       # set ntp server
dhcp-option=6,192.168.10.198    # set the dns server
dhcp-option=44,192.168.10.198   # set WINS server(s)
dhcp-option=46,2                # set netbios node type
dhcp-option=vendor:MSFT,2,1i
dhcp-leasefile=/var/db/dnsmasq.leases
domain-needed
domain=tcentral.local
except-interface=lo0
expand-hosts
filterwin2k
user=nobody
group=nobody
log-facility=LOCAL7
log-queries
resolv-file=/usr/local/etc/resolv.conf

server=/10.168.192.in-addr.arpa/127.53.0.1
server=/tcentral.local/127.53.0.1

server=/poughkeepsieschools.org/10.20.0.16
server=/pcsd/10.20.0.64
server=/20.10.in-addr.arpa/10.20.0.64
# EOF

Thanks in advance, and sorry if I am missing something glaringly  
obvious.

Suggestions and criticism are welcomed.





More information about the Dnsmasq-discuss mailing list