Bind+DLZ writing huge number of logs to /var/log/messages. Impacting capacity.

Mike Toler mike.toler at prodeasystems.com
Thu Jul 19 16:26:03 UTC 2007


Ok, I think I'm onto the culprit here for my capacity issues, but I just
don't understand what is going on.
I have built a test Postgres database with ~130k entries in it.  Each
entry looks like:
test-172-X.YYY.ZZZ, where x is 1 or 2, YYY is 1 to 255 and ZZZ is 1 to
255.

When I do a query, I get the correct response from Bind:
	nslookup test-172-2-255-251.myexample2.com
	Server:         <ip>
	Address:        <ip>#53

	Name:   test-172-2-255-251.myexample2.com
	Address: 172.2.255.251

However, in the /var/log/message file, I see a huge number lines of
"errors" for that lookup.  Did I somehow build bind so that it's debug
level is always turned on?   I've tried starting it with "named -d 0",
I've removed all logging info from my /etc/named.conf.  Before I removed
all the log related lines from my named.conf, these logs all had "error"
listed as their log level.

Anyone got any ideas?


Jul 19 17:10:48 netcool2 named[3482]: 141 Getting DBI
Jul 19 17:10:48 netcool2 named[3482]: 141 Got DBI - checking query
Jul 19 17:10:48 netcool2 named[3482]: 141 checked query
Jul 19 17:10:48 netcool2 named[3482]: 141 did zone
Jul 19 17:10:48 netcool2 named[3482]: 141 did record
Jul 19 17:10:48 netcool2 named[3482]: 141 did client
Jul 19 17:10:48 netcool2 named[3482]: 141 built query
Jul 19 17:10:48 netcool2 named[3482]: 141 query is 'select zone from
dns_records where zone = 'test-172-2-255-251.myexample2.com' limit 1'
Jul 19 17:10:48 netcool2 named[3482]: 141 executing query for 0 time
Jul 19 17:10:48 netcool2 named[3482]: 141 rs ok
Jul 19 17:10:48 netcool2 named[3482]: 141 cleaning up
Jul 19 17:10:48 netcool2 named[3482]: 141 returning
Jul 19 17:10:48 netcool2 named[3482]: 698 Getting DBI
Jul 19 17:10:48 netcool2 named[3482]: 698 Got DBI - checking query
Jul 19 17:10:48 netcool2 named[3482]: 698 checked query
Jul 19 17:10:48 netcool2 named[3482]: 698 did zone
Jul 19 17:10:48 netcool2 named[3482]: 698 did record
Jul 19 17:10:48 netcool2 named[3482]: 698 did client
Jul 19 17:10:48 netcool2 named[3482]: 698 built query
Jul 19 17:10:48 netcool2 named[3482]: 698 query is 'select zone from
dns_records where zone = 'myexample2.com' limit 1'
Jul 19 17:10:48 netcool2 named[3482]: 698 executing query for 0 time
Jul 19 17:10:48 netcool2 named[3482]: 698 rs ok
Jul 19 17:10:48 netcool2 named[3482]: 698 cleaning up
Jul 19 17:10:48 netcool2 named[3482]: 698 returning
Jul 19 17:10:48 netcool2 named[3482]: 619 Getting DBI
Jul 19 17:10:48 netcool2 named[3482]: 619 Got DBI - checking query
Jul 19 17:10:48 netcool2 named[3482]: 619 checked query
Jul 19 17:10:48 netcool2 named[3482]: 619 did zone
Jul 19 17:10:48 netcool2 named[3482]: 619 did record
Jul 19 17:10:48 netcool2 named[3482]: 619 did client
Jul 19 17:10:48 netcool2 named[3482]: 619 built query
Jul 19 17:10:48 netcool2 named[3482]: 619 query is 'select ttl, type,
mx_priority, data, resp_person, serial, refresh, retry,
expire, minimum from dns_records where host = 'test-172-2-255-251' and
zone ='myexample2.com''
Jul 19 17:10:48 netcool2 named[3482]: 619 executing query for 0 time
Jul 19 17:10:48 netcool2 named[3482]: 619 rs ok
Jul 19 17:10:48 netcool2 named[3482]: 619 cleaning up
Jul 19 17:10:48 netcool2 named[3482]: 619 returning
Jul 19 17:10:48 netcool2 named[3482]: 518 Getting DBI
Jul 19 17:10:48 netcool2 named[3482]: 518 Got DBI - checking query
Jul 19 17:10:48 netcool2 named[3482]: 518 checked query
Jul 19 17:10:48 netcool2 named[3482]: 518 did zone
Jul 19 17:10:48 netcool2 named[3482]: 518 did record
Jul 19 17:10:48 netcool2 named[3482]: 518 did client
Jul 19 17:10:48 netcool2 named[3482]: 518 built query
Jul 19 17:10:48 netcool2 named[3482]: 518 query is 'select ttl, type,
mx_priority, data, resp_person, serial, refresh, retry,
expire, minimum from dns_records where host = '@' and  zone
='myexample2.com''
Jul 19 17:10:48 netcool2 named[3482]: 518 executing query for 0 time
Jul 19 17:10:48 netcool2 named[3482]: 518 rs ok
Jul 19 17:10:48 netcool2 named[3482]: 518 cleaning up
Jul 19 17:10:48 netcool2 named[3482]: 518 returning
Jul 19 17:10:48 netcool2 named[3482]: 596 Getting DBI
Jul 19 17:10:48 netcool2 named[3482]: 596 Got DBI - checking query
Jul 19 17:10:48 netcool2 named[3482]: 596 cleaning up
Jul 19 17:10:48 netcool2 named[3482]: 596 returning
Michael





This message is confidential to Prodea Systems, Inc unless otherwise indicated 
or apparent from its nature. This message is directed to the intended recipient 
only, who may be readily determined by the sender of this message and its 
contents. If the reader of this message is not the intended recipient, or an 
employee or agent responsible for delivering this message to the intended 
recipient:(a)any dissemination or copying of this message is strictly 
prohibited; and(b)immediately notify the sender by return message and destroy 
any copies of this message in any form(electronic, paper or otherwise) that you 
have.The delivery of this message and its information is neither intended to be 
nor constitutes a disclosure or waiver of any trade secrets, intellectual 
property, attorney work product, or attorney-client communications. The 
authority of the individual sending this message to legally bind Prodea Systems  
is neither apparent nor implied,and must be independently verified.


More information about the bind-users mailing list