Need to improve named performance

Ed LaFrance edl at connexinternet.com
Sun Nov 11 21:57:16 UTC 2012


Hello -

Thanks for chiming in. Named is PID 8349 in my case. Here's a snippet of 
the output from strace:

[pid  8351] time( <unfinished ...>
[pid  8352] <... sendmsg resumed> )     = 56 <0.000104>
[pid  8352] recvmsg(515, {msg_name(16)={sa_family=AF_INET, 
sin_port=htons(38385), sin_addr=inet_addr("205.188.158.143")}, 
msg_iov(1)=[{"Q&\0\0\0\1\0\0\0\0\0\1\003157\003161\00272\00264\7in-ad"..., 
4096}], msg_controllen=20, {cmsg_len=20, cmsg_level=SOL_SOCKET, 
cmsg_type=0x1d /* SCM_??? */, ...}, msg_flags=0}, 0) = 55 <0.000031>
[pid  8351] <... time resumed> NULL)    = 1352668045 <0.000353>
[pid  8352] futex(0x9b6aecc, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
[pid  8351] stat64("/etc/localtime", {st_mode=S_IFREG|0644, 
st_size=2819, ...}) = 0 <0.000109>
[pid  8351] stat64("/etc/localtime", {st_mode=S_IFREG|0644, 
st_size=2819, ...}) = 0 <0.000086>
[pid  8351] stat64("/etc/localtime", {st_mode=S_IFREG|0644, 
st_size=2819, ...}) = 0 <0.000084>
[pid  8351] send(3, "<30>Nov 11 13:07:25 named[8349]:"..., 107, 
MSG_NOSIGNAL) = 107 <0.015232>
[pid  8351] futex(0x9b6aecc, FUTEX_WAKE_PRIVATE, 1 <unfinished ...>
[pid  8353] <... futex resumed> )       = 0 <0.052813>
[pid  8351] <... futex resumed> )       = 1 <0.000125>
[pid  8353] time(NULL)                  = 1352668045 <0.000020>
[pid  8353] stat64("/etc/localtime", {st_mode=S_IFREG|0644, 
st_size=2819, ...}) = 0 <0.000025>
[pid  8353] stat64("/etc/localtime", {st_mode=S_IFREG|0644, 
st_size=2819, ...}) = 0 <0.000022>
[pid  8351] sendmsg(513, {msg_name(16)={sa_family=AF_INET, 
sin_port=htons(38162), sin_addr=inet_addr("205.188.158.207")}, 
msg_iov(1)=[{"@%\204\0\0\1\0\1\0\2\0\1\003249\00221\003140\003204\7in-a"..., 
138}], msg_controllen=0, msg_flags=0}, 0 <unfinished ...>
[pid  8353] stat64("/etc/localtime",  <unfinished ...>
[pid  8351] <... sendmsg resumed> )     = 138 <0.000048>
[pid  8353] <... stat64 resumed> {st_mode=S_IFREG|0644, st_size=2819, 
...}) = 0 <0.000041>
[pid  8351] recvmsg(513,  <unfinished ...>
[pid  8353] send(3, "<30>Nov 11 13:07:25 named[8349]:"..., 103, 
MSG_NOSIGNAL <unfinished ...>
[pid  8351] <... recvmsg resumed> {msg_name(16)={sa_family=AF_INET, 
sin_port=htons(53507), sin_addr=inet_addr("205.188.158.206")}, 
msg_iov(1)=[{"\244\273\0\0\0\1\0\0\0\0\0\1\003246\003161\00272\00264\7in-ad"..., 
4096}], msg_controllen=20, {cmsg_len=20, cmsg_level=SOL_SOCKET, 
cmsg_type=0x1d /* SCM_??? */, ...}, msg_flags=0}, 0) = 55 <0.000086>
[pid  8351] futex(0x9b6aecc, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
[pid  8353] <... send resumed> )        = 103 <0.015034>
[pid  8353] futex(0x9b6aecc, FUTEX_WAKE_PRIVATE, 1) = 1 <0.000025>
[pid  8350] <... futex resumed> )       = 0 <0.051772>
[pid  8350] time( <unfinished ...>
[pid  8353] sendmsg(513, {msg_name(16)={sa_family=AF_INET, 
sin_port=htons(60702), sin_addr=inet_addr("64.12.139.17")}, 
msg_iov(1)=[{"\343F\204\0\0\1\0\1\0\2\0\1\003251\003160\00272\00264\7in-ad"..., 
151}], msg_controllen=0, msg_flags=0}, 0 <unfinished ...>
[pid  8350] <... time resumed> NULL)    = 1352668045 <0.000210>
[pid  8353] <... sendmsg resumed> )     = 151 <0.000084>
[pid  8350] stat64("/etc/localtime",  <unfinished ...>
[pid  8353] recvmsg(513,  <unfinished ...>
[pid  8350] <... stat64 resumed> {st_mode=S_IFREG|0644, st_size=2819, 
...}) = 0 <0.000085>
[pid  8353] <... recvmsg resumed> {msg_name(16)={sa_family=AF_INET, 
sin_port=htons(3794), sin_addr=inet_addr("64.12.139.19")}, 
msg_iov(1)=[{"|\354\0\0\0\1\0\0\0\0\0\1\00230\003160\00272\00264\7in-add"..., 
4096}], msg_controllen=20, {cmsg_len=20, cmsg_level=SOL_SOCKET, 
cmsg_type=0x1d /* SCM_??? */, ...}, msg_flags=0}, 0) = 54 <0.000150>
[pid  8350] stat64("/etc/localtime",  <unfinished ...>
[pid  8353] futex(0x9b6aecc, FUTEX_WAIT_PRIVATE, 2, NULL <unfinished ...>
[pid  8350] <... stat64 resumed> {st_mode=S_IFREG|0644, st_size=2819, 
...}) = 0 <0.000076>
[pid  8350] stat64("/etc/localtime", {st_mode=S_IFREG|0644, 
st_size=2819, ...}) = 0 <0.000029>
[pid  8350] send(3, "<30>Nov 11 13:07:25 named[8349]:"..., 102, 
MSG_NOSIGNAL <unfinished ...>


On 11/11/2012 1:46 PM, Florian Weimer wrote:
> * Ed LaFrance:
>
>> Running BIND 9.3.6-P1-RedHat-9.3.6-16.P1.el5 on a quadcore xeon server
>> (3Ghz) with 2GB RAM. Named is being used only for rDNS queries against
>> our address space.
>
> You should really upgrade to the latest version on that branch (likely
> bind-9.3.6-20.P1.el5_8.5).
>
>> The bottom line is: I need to improve named performance. Tcpdump only
>> shows about 20 requests per second on average, I would estimate. This
>> should be handled easily, but instead it's gagging on it and the
>> requests are stacking up.
>
> Something is stalling the named process.  Try to run "strace -T -f -p
> 4509" (4509 is the PID for the named process) and see where named
> spends its time.  The top output you quoted suggests that the process
> is not spinning in user space.
>

-- 
(800) 362-7579 ext 1

+-------------------------------------------------------+
+ Colocation    Dedicated Servers   IPv4 & IPv6 Transit +
+-------------------------------------------------------+
Connex Internet Services, Inc.     direct: (916) 265-1568
11230 Gold Express Dr #310-313        fax: (916) 880-5663
Gold River, CA 95670            http://connexinternet.com
+-------------------------------------------------------+



More information about the bind-users mailing list