Bind problems

Brett Simpson simpsonb at hillsboroughcounty.org
Wed Oct 1 19:56:31 UTC 2003


I'm having a problem with Bind 9.2.2-22 rpm on my Primary DNS server crashing 
once or twice a week. I'm running Redhat 9.0 on a 1.0ghz with 900mb of ram.

At least this time before it crashed I saw a bunch of these messages that I 
normally don't see all at once. Also the crash seemed to happen after I did a 
reload of my config after making some changes through webmin.

I'm seeing a thousand of these:
Oct 01 14:42:05.132 database: debug 1: flush_deletions: 27 nodes of 29733 in 
tree
Oct 01 14:42:05.132 database: debug 1: no_references: delete from rbt: 
0x834c1f8 wmr02sea.activate.NET
Oct 01 14:42:05.132 database: debug 1: cache cleaner: checked 1000 nodes, mem 
inuse 2960932, sleeping
Oct 01 14:42:05.133 database: debug 1: flush_deletions: 64 nodes of 29732 in 
tree
Oct 01 14:42:05.133 database: debug 1: no_references: delete from rbt: 
0x8687268 mail.adelphia.NET
Oct 01 14:42:05.133 database: debug 1: no_references: delete from rbt: 
0x86c8c58 www.admo.NET
Oct 01 14:42:05.133 database: debug 1: no_references: delete from rbt: 
0x85e5bc8 content-epi.dev.advance.NET
Oct 01 14:42:05.133 database: debug 1: no_references: delete from rbt: 
0x85e0818 content-epi.live.advance.NET
Oct 01 14:42:05.133 database: debug 1: no_references: delete from rbt: 
0x87f0dd8 cs.about.akadns.NET
Oct 01 14:42:05.133 database: debug 1: no_references: delete from rbt: 
0x856bad0 js.about.akadns.NET
Oct 01 14:42:05.133 database: debug 1: no_references: delete from rbt: 
0x86229c0 www.about.akadns.NET
Oct 01 14:42:05.133 database: debug 1: no_references: delete from rbt: 
0x8663980 apple.com.akadns.NET
Oct 01 14:42:05.133 database: debug 1: no_references: delete from rbt: 
0x84b2410 hb.lycos.com.akadns.NET
Oct 01 14:42:05.133 database: debug 1: no_references: delete from rbt: 
0x8771a18 bs.eyeblaster.akadns.NET
Oct 01 14:42:05.133 database: debug 1: no_references: delete from rbt: 
0x87a33c8 ebay1.ipixmedia.georedirector.akadns.NET
Oct 01 14:42:05.133 database: debug 1: no_references: delete from rbt: 
0x879ba48 ebay3.ipixmedia.georedirector.akadns.NET
Oct 01 14:42:05.133 database: debug 1: no_references: delete from rbt: 
0x86cc218 images.google.akadns.NET
Oct 01 14:42:05.133 database: debug 1: no_references: delete from rbt: 
0x81ebe68 c.microsoft.akadns.NET
Oct 01 14:42:05.133 database: debug 1: no_references: delete from rbt: 
0x85d9508 dl-geodir.microsoft.akadns.NET
Oct 01 14:42:05.133 database: debug 1: no_references: delete from rbt: 
0x862f530 searchservice.go.microsoft.akadns.NET

Did a reload of the config:
Oct 01 14:44:58.711 database: debug 5: Cleaning interval for adb:  8 buckets 
every 30 seconds, 1009 buckets in system, 3600 cl.interval
Oct 01 14:44:58.711 general: debug 3: dns_requestmgr_create
Oct 01 14:44:58.711 general: debug 3: dns_requestmgr_create: 0x87ba2f8
Oct 01 14:44:58.711 general: debug 3: dns_requestmgr_whenshutdown
Oct 01 14:44:58.712 general: debug 3: replacing zone database
Oct 01 14:44:58.712 general: debug 1: now using logging configuration from 
config file
Oct 01 14:44:58.726 resolver: debug 3: res 0x80d36d0: shutdown
Oct 01 14:44:58.727 resolver: debug 3: res 0x80d36d0: exiting
Oct 01 14:44:58.727 database: debug 5: killing name 0x8814778
Oct 01 14:44:58.727 database: debug 5: killing name 0x87cce68

A ton more in between.....

Oct 01 14:44:59.204 database: debug 5: killing name 0x861a640
Oct 01 14:44:59.204 database: debug 5: killing name 0x85968e0
Oct 01 14:44:59.204 database: debug 5: killing name 0x84dc330
Oct 01 14:44:59.204 database: debug 5: killing name 0x8421c38
Oct 01 14:44:59.204 database: debug 5: killing name 0x837eb58
Oct 01 14:44:59.204 database: debug 5: killing name 0x833c480
Oct 01 14:44:59.204 database: debug 5: killing name 0x8257fc0
Oct 01 14:44:59.204 database: debug 5: killing name 0x825bcc0
Oct 01 14:44:59.204 database: debug 5: killing name 0x815a258
Oct 01 14:44:59.204 general: debug 3: dns_requestmgr_shutdown: 0x80e87f8
Oct 01 14:44:59.205 general: debug 3: send_shutdown_events: 0x80e87f8
Oct 01 14:44:59.205 general: debug 1: load_configuration: success
Oct 01 14:44:59.205 general: debug 1: zone 6.10.in-addr.arpa/IN: skipping 
load: master file older than last load
Oct 01 14:44:59.205 general: debug 1: zone 0.0.127.in-addr.arpa/IN: skipping 
load: master file older than last load
Oct 01 14:44:59.205 general: debug 1: zone 16.172.in-addr.arpa/IN: skipping 
load: master file older than last load

Oct 01 14:48:21.770 database: debug 5: dns_adb_createfind: found A for name 
0x841cb30 in db
Oct 01 14:48:21.770 database: debug 5: dns_adb_createfind: found A for name 
0x823a070 in db
Oct 01 14:48:21.770 resolver: debug 3: fctx 0x8349988: query
Oct 01 14:48:21.770 resolver: debug 3: resquery 0x881cc70 (fctx 0x8349988): 
send
Oct 01 14:48:21.770 resolver: debug 3: resquery 0x881cc70 (fctx 0x8349988): 
sent
Oct 01 14:48:21.770 resolver: debug 3: resquery 0x881cc70 (fctx 0x8349988): 
senddone

Named processes are no longer running so I then restart them:

Oct 01 14:53:13.096 general: debug 1: now using logging configuration from 
config file
Oct 01 14:53:13.097 general: debug 1: load_configuration: success
Oct 01 14:53:13.097 general: debug 1: zone 6.10.in-addr.arpa/IN: starting load
Oct 01 14:53:13.106 general: debug 2: zone 6.10.in-addr.arpa/IN: number of 
nodes in database: 8
Oct 01 14:53:13.106 general: debug 1: zone 6.10.in-addr.arpa/IN: loaded
Oct 01 14:53:13.106 general: debug 3: no journal file, but that's OK




More information about the bind-users mailing list