Sendmail slow?
Anybody know why sendmail is so slow to respond initially to a sent
email? When I hit the send button it takes about 8 seconds for the email to go. I'm running Ubuntu server 8.10 and sendmail 8.14.3. I had my mail server on a Fedora box before this and it would send mail immediately upon hitting the send button in my mail client. As far as I can tell, sendmail is configured identically to the old Fedora box. I have greet pause enabled but disabled for my sending address. If I enable it for my address it takes that much longer, so I know at least that is working correctly. Any ideas where to start looking would be greatly appreciated. Thanks, -- Knute Johnson "The urge to save humanity is almost always a false front for the urge to rule." H.L. Mencken -- ubuntu-users mailing list ubuntu-users@lists.ubuntu.com Modify settings or unsubscribe at: https://lists.ubuntu.com/mailman/listinfo/ubuntu-users |
Sendmail slow?
On Fri, 2009-02-13 at 14:48 -0800, Knute Johnson wrote:
> Anybody know why sendmail is so slow to respond initially to a sent > email? When I hit the send button it takes about 8 seconds for the > email to go. I'm running Ubuntu server 8.10 and sendmail 8.14.3. I had > my mail server on a Fedora box before this and it would send mail > immediately upon hitting the send button in my mail client. As far as I > can tell, sendmail is configured identically to the old Fedora box. I > have greet pause enabled but disabled for my sending address. If I > enable it for my address it takes that much longer, so I know at least > that is working correctly. Any ideas where to start looking would be > greatly appreciated. Could be a host lookup issue. Your local mail agent sends to localhost which should be mapped to 127.0.0.1. A reverse lookup is needed to display the hostname in the greeting message. You could be seeing the timeout delay. Check /etc/hosts for an entry for the 127.0.0.1 address. -- Smoot Carl-Mitchell Computer Systems and Network Consultant smoot@tic.com +1 480 922 7313 cell: +1 602 421 9005 -- ubuntu-users mailing list ubuntu-users@lists.ubuntu.com Modify settings or unsubscribe at: https://lists.ubuntu.com/mailman/listinfo/ubuntu-users |
Sendmail slow?
Smoot Carl-Mitchell wrote:
> On Fri, 2009-02-13 at 14:48 -0800, Knute Johnson wrote: >> Anybody know why sendmail is so slow to respond initially to a sent >> email? When I hit the send button it takes about 8 seconds for the >> email to go. I'm running Ubuntu server 8.10 and sendmail 8.14.3. I had >> my mail server on a Fedora box before this and it would send mail >> immediately upon hitting the send button in my mail client. As far as I >> can tell, sendmail is configured identically to the old Fedora box. I >> have greet pause enabled but disabled for my sending address. If I >> enable it for my address it takes that much longer, so I know at least >> that is working correctly. Any ideas where to start looking would be >> greatly appreciated. > > Could be a host lookup issue. Your local mail agent sends to localhost > which should be mapped to 127.0.0.1. A reverse lookup is needed to > display the hostname in the greeting message. You could be seeing the > timeout delay. Check /etc/hosts for an entry for the 127.0.0.1 address. Thanks for the idea. The localhost is in /etc/hosts. I think I'll put the wireshark on it though and see if there is some delay doing the DNS. The server is located locally but I am looking up the addresses. -- Knute Johnson "The urge to save humanity is almost always a false front for the urge to rule." H.L. Mencken -- ubuntu-users mailing list ubuntu-users@lists.ubuntu.com Modify settings or unsubscribe at: https://lists.ubuntu.com/mailman/listinfo/ubuntu-users |
Sendmail slow?
Knute Johnson wrote:
> Smoot Carl-Mitchell wrote: >> On Fri, 2009-02-13 at 14:48 -0800, Knute Johnson wrote: >>> Anybody know why sendmail is so slow to respond initially to a sent >>> email? When I hit the send button it takes about 8 seconds for the >>> email to go. I'm running Ubuntu server 8.10 and sendmail 8.14.3. I had >>> my mail server on a Fedora box before this and it would send mail >>> immediately upon hitting the send button in my mail client. As far as I >>> can tell, sendmail is configured identically to the old Fedora box. I >>> have greet pause enabled but disabled for my sending address. If I >>> enable it for my address it takes that much longer, so I know at least >>> that is working correctly. Any ideas where to start looking would be >>> greatly appreciated. >> Could be a host lookup issue. Your local mail agent sends to localhost >> which should be mapped to 127.0.0.1. A reverse lookup is needed to >> display the hostname in the greeting message. You could be seeing the >> timeout delay. Check /etc/hosts for an entry for the 127.0.0.1 address. > > Thanks for the idea. The localhost is in /etc/hosts. I think I'll put > the wireshark on it though and see if there is some delay doing the DNS. > The server is located locally but I am looking up the addresses. > Wireshark shows a 5 second period between the connection to port 587 and the 220 message from sendmail. That's where my problem is. Do you know of any configuration that would cause that sort of a delay? Thanks, -- Knute Johnson "The urge to save humanity is almost always a false front for the urge to rule." H.L. Mencken -- ubuntu-users mailing list ubuntu-users@lists.ubuntu.com Modify settings or unsubscribe at: https://lists.ubuntu.com/mailman/listinfo/ubuntu-users |
Sendmail slow?
On Fri, 2009-02-13 at 19:57 -0800, Knute Johnson wrote:
> Wireshark shows a 5 second period between the connection to port 587 and > the 220 message from sendmail. That's where my problem is. > > Do you know of any configuration that would cause that sort of a delay? You might try running strace on the listening SMTP process with timer option to see where the delay is creeping in. Something like sudo strace -f -p <pid> -o <output_file> -t You need the -f to pick up the forked processes as the listening sendmail handles the connections. The -t will give you timestamps on each system call. -- Smoot Carl-Mitchell Computer Systems and Network Consultant smoot@tic.com +1 480 922 7313 cell: +1 602 421 9005 -- ubuntu-users mailing list ubuntu-users@lists.ubuntu.com Modify settings or unsubscribe at: https://lists.ubuntu.com/mailman/listinfo/ubuntu-users |
Sendmail slow?
Smoot Carl-Mitchell wrote:
> You might try running strace on the listening SMTP process with timer > option to see where the delay is creeping in. Something like > > sudo strace -f -p <pid> -o <output_file> -t > > You need the -f to pick up the forked processes as the listening > sendmail handles the connections. The -t will give you timestamps on > each system call. 16288 is sendmail-mta. I'm not sure what this shows except that there is a 5 second period where nothing is happening. Any input greatly accepted :-). Thanks, 16288 17:05:16 select(7, [4 5 6], NULL, NULL, {2, 170000}) = 0 (Timeout) 16288 17:05:19 rt_sigprocmask(SIG_BLOCK, [ALRM], [], 8) = 0 16288 17:05:19 time(NULL) = 1234659919 16288 17:05:19 time(NULL) = 1234659919 16288 17:05:19 open("/proc/loadavg", O_RDONLY) = 8 16288 17:05:19 fstat64(8, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0 16288 17:05:19 read(8, "0.00 0.00 0.00 2/170 5091 ", 1024) = 26 16288 17:05:19 close(8) = 0 16288 17:05:19 time(NULL) = 1234659919 16288 17:05:19 time(NULL) = 1234659919 16288 17:05:19 open("/proc/loadavg", O_RDONLY) = 8 16288 17:05:19 fstat64(8, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0 16288 17:05:19 read(8, "0.00 0.00 0.00 2/170 5091 ", 1024) = 26 16288 17:05:19 close(8) = 0 16288 17:05:19 time(NULL) = 1234659919 16288 17:05:19 rt_sigprocmask(SIG_UNBLOCK, [ALRM], [ALRM], 8) = 0 16288 17:05:19 select(7, [4 5 6], NULL, NULL, {5, 0}) = 1 (in [5], left {4, 580000}) 16288 17:05:19 accept(5, {sa_family=AF_INET, sin_port=htons(1116), sin_addr=inet_addr("216.240.58.138")}, [16]) = 8 16288 17:05:19 rt_sigprocmask(SIG_BLOCK, [ALRM], [], 8) = 0 16288 17:05:19 time(NULL) = 1234659919 16288 17:05:19 pipe([9, 10]) = 0 16288 17:05:19 rt_sigprocmask(SIG_BLOCK, [CHLD], [ALRM], 8) = 0 16288 17:05:19 clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGC HLD, child_tidptr=0xb78c26f8) = 5092 16288 17:05:19 rt_sigprocmask(SIG_UNBLOCK, [CHLD], [ALRM CHLD], 8) = 0 16288 17:05:19 close(9) = 0 5092 17:05:19 close(7 <unfinished ...> 16288 17:05:19 close(8 <unfinished ...> 5092 17:05:19 <... close resumed> ) = 0 16288 17:05:19 <... close resumed> ) = 0 16288 17:05:19 close(10 <unfinished ...> 5092 17:05:19 rt_sigprocmask(SIG_UNBLOCK, [ALRM], <unfinished ...> 16288 17:05:19 <... close resumed> ) = 0 5092 17:05:19 <... rt_sigprocmask resumed> [ALRM CHLD], 8) = 0 16288 17:05:19 rt_sigprocmask(SIG_BLOCK, [ALRM], <unfinished ...> 5092 17:05:19 rt_sigprocmask(SIG_UNBLOCK, [CHLD], <unfinished ...> 16288 17:05:19 <... rt_sigprocmask resumed> [ALRM], 8) = 0 5092 17:05:19 <... rt_sigprocmask resumed> [CHLD], 8) = 0 16288 17:05:19 time( <unfinished ...> 5092 17:05:19 rt_sigaction(SIGCHLD, {SIG_DFL}, <unfinished ...> 16288 17:05:19 <... time resumed> NULL) = 1234659919 5092 17:05:19 <... rt_sigaction resumed> {0xb7f11520, [], SA_RESTART}, 8) = 0 16288 17:05:19 time( <unfinished ...> 5092 17:05:19 rt_sigaction(SIGHUP, {SIG_DFL}, <unfinished ...> 16288 17:05:19 <... time resumed> NULL) = 1234659919 5092 17:05:19 <... rt_sigaction resumed> {0xb7f5a0e0, [], SA_RESTART}, 8) = 0 16288 17:05:19 open("/proc/loadavg", O_RDONLY <unfinished ...> 5092 17:05:19 rt_sigaction(SIGTERM, {0xb7f02a20, [], SA_RESTART}, <unfinished ...> 16288 17:05:19 <... open resumed> ) = 8 5092 17:05:19 <... rt_sigaction resumed> {0xb7f5a010, [], SA_RESTART}, 8) = 0 16288 17:05:19 fstat64(8, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0 16288 17:05:19 read(8, <unfinished ...> 5092 17:05:19 close(4 <unfinished ...> 16288 17:05:19 <... read resumed> "0.00 0.00 0.00 2/171 5092 ", 1024) = 26 5092 17:05:19 <... close resumed> ) = 0 16288 17:05:19 close(8 <unfinished ...> 5092 17:05:19 close(5 <unfinished ...> 16288 17:05:19 <... close resumed> ) = 0 5092 17:05:19 <... close resumed> ) = 0 16288 17:05:19 time( <unfinished ...> 5092 17:05:19 close(6 <unfinished ...> 16288 17:05:19 <... time resumed> NULL) = 1234659919 5092 17:05:19 <... close resumed> ) = 0 16288 17:05:19 time( <unfinished ...> 5092 17:05:19 rt_sigaction(SIGCHLD, {0xb7f11520, [], SA_RESTART}, <unfinished ...> 16288 17:05:19 <... time resumed> NULL) = 1234659919 5092 17:05:19 <... rt_sigaction resumed> {SIG_DFL}, 8) = 0 16288 17:05:19 open("/proc/loadavg", O_RDONLY <unfinished ...> 5092 17:05:19 close(10 <unfinished ...> 16288 17:05:19 <... open resumed> ) = 8 5092 17:05:19 <... close resumed> ) = 0 16288 17:05:19 fstat64(8, <unfinished ...> 5092 17:05:19 read(9, <unfinished ...> 16288 17:05:19 <... fstat64 resumed> {st_mode=S_IFREG|0444, st_size=0, ...}) = 0 5092 17:05:19 <... read resumed> "", 1) = 0 16288 17:05:19 read(8, <unfinished ...> 5092 17:05:19 close(9 <unfinished ...> 16288 17:05:19 <... read resumed> "0.00 0.00 0.00 2/171 5092 ", 1024) = 26 5092 17:05:19 <... close resumed> ) = 0 16288 17:05:19 close(8) = 0 5092 17:05:19 open("/etc/hosts", O_RDONLY|0x80000 /* O_??? */ <unfinished ...> 16288 17:05:19 time( <unfinished ...> 5092 17:05:19 <... open resumed> ) = 4 16288 17:05:19 <... time resumed> NULL) = 1234659919 5092 17:05:19 fstat64(4, <unfinished ...> 16288 17:05:19 rt_sigprocmask(SIG_UNBLOCK, [ALRM], <unfinished ...> 5092 17:05:19 <... fstat64 resumed> {st_mode=S_IFREG|0644, st_size=373, ...}) = 0 16288 17:05:19 <... rt_sigprocmask resumed> [ALRM], 8) = 0 5092 17:05:19 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0 <unfinished ...> 16288 17:05:19 select(7, [4 5 6], NULL, NULL, {5, 0} <unfinished ...> 5092 17:05:19 <... mmap2 resumed> ) = 0xb7ed6000 5092 17:05:19 read(4, "127.0.0.1 localhost #216.240.58."..., 4096) = 373 5092 17:05:19 read(4, "", 4096) = 0 5092 17:05:19 close(4) = 0 5092 17:05:19 munmap(0xb7ed6000, 4096) = 0 5092 17:05:19 stat64("/etc/resolv.conf", {st_mode=S_IFREG|0644, st_size=91, ...}) = 0 5092 17:05:19 socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 4 5092 17:05:19 connect(4, {sa_family=AF_INET, sin_port=htons(53), sin_addr=inet_addr("207.181.0.10")}, 28) = 0 5092 17:05:19 fcntl64(4, F_GETFL) = 0x2 (flags O_RDWR) 5092 17:05:19 fcntl64(4, F_SETFL, O_RDWR|O_NONBLOCK) = 0 5092 17:05:19 gettimeofday({1234659919, 555021}, NULL) = 0 5092 17:05:19 poll([{fd=4, events=POLLOUT, revents=POLLOUT}], 1, 0) = 1 5092 17:05:19 send(4, "237201 1 03138 0258 03240 03216"..., 45, MSG_NOSIGNAL) = 45 5092 17:05:19 poll([{fd=4, events=POLLIN, revents=POLLIN}], 1, 5000) = 1 5092 17:05:19 ioctl(4, FIONREAD, [82]) = 0 5092 17:05:19 recvfrom(4, "23720205200 1 1 03138 0258 03240 03"..., 1024, 0, {sa_family=AF_INET, sin_port=htons(53), sin_addr=inet_addr("207.181.0.10")}, [16]) = 82 5092 17:05:19 close(4) = 0 5092 17:05:19 fcntl64(8, F_GETFL) = 0x2 (flags O_RDWR) 5092 17:05:19 dup(8) = 4 5092 17:05:19 fcntl64(4, F_GETFL) = 0x2 (flags O_RDWR) 5092 17:05:19 getsockname(8, {sa_family=AF_INET, sin_port=htons(587), sin_addr=inet_addr("216.240.58.140")}, [16]) = 0 5092 17:05:19 open("/etc/hosts", O_RDONLY|0x80000 /* O_??? */) = 5 5092 17:05:19 fstat64(5, {st_mode=S_IFREG|0644, st_size=373, ...}) = 0 5092 17:05:19 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7ed6000 5092 17:05:19 read(5, "127.0.0.1 localhost #216.240.58."..., 4096) = 373 5092 17:05:19 close(5) = 0 5092 17:05:19 munmap(0xb7ed6000, 4096) = 0 5092 17:05:19 getegid32() = 120 5092 17:05:19 setgroups32(1, [120]) = 0 5092 17:05:19 ioctl(8, SNDCTL_TMR_TIMEBASE or TCGETS, 0xbffb7bb8) = -1 EINVAL (Invalid argument) 5092 17:05:19 getpeername(8, {sa_family=AF_INET, sin_port=htons(1116), sin_addr=inet_addr("216.240.58.138")}, [16]) = 0 5092 17:05:19 stat64("/etc/resolv.conf", {st_mode=S_IFREG|0644, st_size=91, ...}) = 0 5092 17:05:19 stat64("/etc/resolv.conf", {st_mode=S_IFREG|0644, st_size=91, ...}) = 0 5092 17:05:19 open("/etc/hosts", O_RDONLY|0x80000 /* O_??? */) = 5 5092 17:05:19 fstat64(5, {st_mode=S_IFREG|0644, st_size=373, ...}) = 0 5092 17:05:19 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7ed6000 5092 17:05:19 read(5, "127.0.0.1 localhost #216.240.58."..., 4096) = 373 5092 17:05:19 read(5, "", 4096) = 0 5092 17:05:19 close(5) = 0 5092 17:05:19 munmap(0xb7ed6000, 4096) = 0 5092 17:05:19 stat64("/etc/resolv.conf", {st_mode=S_IFREG|0644, st_size=91, ...}) = 0 5092 17:05:19 socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 5 5092 17:05:19 connect(5, {sa_family=AF_INET, sin_port=htons(53), sin_addr=inet_addr("207.181.0.10")}, 28) = 0 5092 17:05:19 fcntl64(5, F_GETFL) = 0x2 (flags O_RDWR) 5092 17:05:19 fcntl64(5, F_SETFL, O_RDWR|O_NONBLOCK) = 0 5092 17:05:19 gettimeofday({1234659919, 562536}, NULL) = 0 5092 17:05:19 poll([{fd=5, events=POLLOUT, revents=POLLOUT}], 1, 0) = 1 5092 17:05:19 send(5, "331S1 1 6knute2faerioconnect"..., 41, MSG_NOSIGNAL) = 41 5092 17:05:19 poll([{fd=5, events=POLLIN, revents=POLLIN}], 1, 5000) = 1 5092 17:05:19 ioctl(5, FIONREAD, [57]) = 0 5092 17:05:19 recvfrom(5, "331S205200 1 1 6knute2faerioconnect"..., 1024, 0, {sa_family=AF_INET, sin_port=htons(53), sin_addr=inet_addr("207.181.0.10")}, [16]) = 57 5092 17:05:19 close(5) = 0 5092 17:05:19 getsockname(8, {sa_family=AF_INET, sin_port=htons(587), sin_addr=inet_addr("216.240.58.140")}, [16]) = 0 5092 17:05:19 open("/etc/services", O_RDONLY|0x80000 /* O_??? */) = 5 5092 17:05:19 fstat64(5, {st_mode=S_IFREG|0644, st_size=18449, ...}) = 0 5092 17:05:19 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7ed6000 5092 17:05:19 read(5, "# Network services, Internet sty"..., 4096) = 4096 5092 17:05:19 close(5) = 0 5092 17:05:19 munmap(0xb7ed6000, 4096) = 0 5092 17:05:19 rt_sigprocmask(SIG_BLOCK, NULL, [], 8) = 0 5092 17:05:19 rt_sigprocmask(SIG_BLOCK, [ALRM], [], 8) = 0 5092 17:05:19 time(NULL) = 1234659919 5092 17:05:19 rt_sigaction(SIGALRM, {0xb7f9e170, [], SA_RESTART}, {0xb7f9e170, [], SA_RESTART}, 8) = 0 5092 17:05:19 alarm(5) = 0 5092 17:05:19 rt_sigprocmask(SIG_UNBLOCK, [ALRM], [ALRM], 8) = 0 5092 17:05:19 socket(PF_INET, SOCK_STREAM, IPPROTO_IP) = 5 5092 17:05:19 bind(5, {sa_family=AF_INET, sin_port=htons(0), sin_addr=inet_addr("216.240.58.140")}, 16) = 0 5092 17:05:19 connect(5, {sa_family=AF_INET, sin_port=htons(113), sin_addr=inet_addr("216.240.58.138")}, 16 <unfinished ...> 16288 17:05:24 <... select resumed> ) = 0 (Timeout) 16288 17:05:24 rt_sigprocmask(SIG_BLOCK, [ALRM], [], 8) = 0 16288 17:05:24 time(NULL) = 1234659924 16288 17:05:24 time(NULL) = 1234659924 16288 17:05:24 open("/proc/loadavg", O_RDONLY) = 8 16288 17:05:24 fstat64(8, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0 16288 17:05:24 read(8, "0.00 0.00 0.00 2/171 5092 ", 1024) = 26 16288 17:05:24 close(8) = 0 16288 17:05:24 time(NULL) = 1234659924 16288 17:05:24 time(NULL) = 1234659924 16288 17:05:24 open("/proc/loadavg", O_RDONLY) = 8 16288 17:05:24 fstat64(8, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0 16288 17:05:24 read(8, "0.00 0.00 0.00 2/171 5092 ", 1024) = 26 16288 17:05:24 close(8) = 0 16288 17:05:24 time(NULL) = 1234659924 16288 17:05:24 rt_sigprocmask(SIG_UNBLOCK, [ALRM], [ALRM], 8) = 0 16288 17:05:24 select(7, [4 5 6], NULL, NULL, {5, 0} <unfinished ...> 5092 17:05:24 <... connect resumed> ) = ? ERESTARTSYS (To be restarted) 5092 17:05:24 --- SIGALRM (Alarm clock) @ 0 (0) --- 5092 17:05:24 alarm(0) = 0 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 alarm(580) = 0 5092 17:05:24 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0 5092 17:05:24 close(5) = 0 5092 17:05:24 getsockopt(8, SOL_IP, IP_OPTIONS, "", [0]) = 0 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 open("/proc/loadavg", O_RDONLY) = 5 5092 17:05:24 fstat64(5, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0 5092 17:05:24 read(5, "0.00 0.00 0.00 1/171 5092 ", 1024) = 26 5092 17:05:24 close(5) = 0 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 stat64("/etc/mail/access.db", {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 lstat64("/", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0 5092 17:05:24 geteuid32() = 0 5092 17:05:24 lstat64("/etc", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0 5092 17:05:24 geteuid32() = 0 5092 17:05:24 lstat64("/etc/mail", {st_mode=S_IFDIR|S_ISGID|0755, st_size=4096, ...}) = 0 5092 17:05:24 geteuid32() = 0 5092 17:05:24 open("/etc/mail/access.db", O_RDONLY) = 5 5092 17:05:24 fstat64(5, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(5, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 stat64("/etc/mail/access.db", {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 open("/etc/mail/access.db", O_RDONLY|O_LARGEFILE) = 6 5092 17:05:24 fcntl64(6, F_SETFD, FD_CLOEXEC) = 0 5092 17:05:24 read(6, " 1 a256 20 10"..., 512) = 512 5092 17:05:24 open("DB_CONFIG", O_RDONLY|O_LARGEFILE) = -1 ENOENT (No such file or directory) 5092 17:05:24 stat64("/var/tmp", {st_mode=S_IFDIR|S_ISVTX|0777, st_size=4096, ...}) = 0 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 gettimeofday({1234659924, 571143}, NULL) = 0 5092 17:05:24 open("/proc/stat", O_RDONLY) = 7 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0 5092 17:05:24 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7ed6000 5092 17:05:24 read(7, "cpu 40339 0 16567 57923774 2284"..., 1024) = 697 5092 17:05:24 read(7, "", 1024) = 0 5092 17:05:24 close(7) = 0 5092 17:05:24 munmap(0xb7ed6000, 4096) = 0 5092 17:05:24 mmap2(NULL, 159744, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7811000 5092 17:05:24 open("/etc/mail/access.db", O_RDONLY|O_LARGEFILE) = 7 5092 17:05:24 fcntl64(7, F_SETFD, FD_CLOEXEC) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 pread64(7, " 1 a256 20 10"..., 4096, 0) = 4096 5092 17:05:24 close(5) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 pread64(7, " 1 1 32 31716 "..., 4096, 4096) = 4096 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 pread64(7, " 1 2 " 21616 "..., 4096, 8192) = 4096 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 open("/etc/hosts.allow", O_RDONLY) = 5 5092 17:05:24 fstat64(5, {st_mode=S_IFREG|0644, st_size=639, ...}) = 0 5092 17:05:24 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7ed6000 5092 17:05:24 read(5, "sendmail: all # /etc/hosts.allow"..., 4096) = 639 5092 17:05:24 close(5) = 0 5092 17:05:24 munmap(0xb7ed6000, 4096) = 0 5092 17:05:24 dup2(4, 1) = 1 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2819, ...}) = 0 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2819, ...}) = 0 5092 17:05:24 open("/proc/loadavg", O_RDONLY) = 5 5092 17:05:24 fstat64(5, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0 5092 17:05:24 read(5, "0.00 0.00 0.00 1/171 5092 ", 1024) = 26 5092 17:05:24 close(5) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 getpeername(8, {sa_family=AF_INET, sin_port=htons(1116), sin_addr=inet_addr("216.240.58.138")}, [16]) = 0 5092 17:05:24 socket(PF_NETLINK, SOCK_RAW, 0) = 5 5092 17:05:24 bind(5, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0 5092 17:05:24 getsockname(5, {sa_family=AF_NETLINK, pid=5092, groups=00000000}, [12]) = 0 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 sendto(5, "24 26 13Tj227I ", 20, 0, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20 5092 17:05:24 recvmsg(5, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000}, msg_iov(1)=[{"0 24 2 Tj227I34423 2102003761 "..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 108 5092 17:05:24 recvmsg(5, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000}, msg_iov(1)=[{"@ 24 2 Tj227I34423 2002003761 "..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 128 5092 17:05:24 recvmsg(5, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000}, msg_iov(1)=[{"24 3 2 Tj227I34423 1 24"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 20 5092 17:05:24 close(5) = 0 5092 17:05:24 getsockname(8, {sa_family=AF_INET, sin_port=htons(587), sin_addr=inet_addr("216.240.58.140")}, [16]) = 0 5092 17:05:24 socket(PF_NETLINK, SOCK_RAW, 0) = 5 5092 17:05:24 bind(5, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 0 5092 17:05:24 getsockname(5, {sa_family=AF_NETLINK, pid=5092, groups=00000000}, [12]) = 0 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 sendto(5, "24 26 13Tj227I ", 20, 0, {sa_family=AF_NETLINK, pid=0, groups=00000000}, 12) = 20 5092 17:05:24 recvmsg(5, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000}, msg_iov(1)=[{"0 24 2 Tj227I34423 2102003761 "..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 108 5092 17:05:24 recvmsg(5, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000}, msg_iov(1)=[{"@ 24 2 Tj227I34423 2002003761 "..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 128 5092 17:05:24 recvmsg(5, {msg_name(12)={sa_family=AF_NETLINK, pid=0, groups=00000000}, msg_iov(1)=[{"24 3 2 Tj227I34423 1 24"..., 4096}], msg_controllen=0, msg_flags=0}, 0) = 20 5092 17:05:24 close(5) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(4, {st_mode=S_IFSOCK|0777, st_size=0, ...}) = 0 5092 17:05:24 fcntl64(8, F_GETFL) = 0x2 (flags O_RDWR) 5092 17:05:24 fcntl64(8, F_SETFL, O_RDWR|O_NONBLOCK) = 0 5092 17:05:24 fstat64(8, {st_mode=S_IFSOCK|0777, st_size=0, ...}) = 0 5092 17:05:24 select(9, [8], NULL, NULL, {0, 0}) = 0 (Timeout) 5092 17:05:24 write(4, "220 knute4.aerioconnect.net ESMT"..., 204) = 204 5092 17:05:24 read(8, 0xb8ec1810, 4096) = -1 EAGAIN (Resource temporarily unavailable) 5092 17:05:24 gettimeofday({1234659924, 580943}, NULL) = 0 5092 17:05:24 select(9, [8], NULL, [8], {300, 0}) = 1 (in [8], left {299, 970000}) 5092 17:05:24 gettimeofday({1234659924, 603867}, NULL) = 0 5092 17:05:24 read(8, "EHLO [192.168.3.5] ", 4096) = 20 5092 17:05:24 fcntl64(4, F_GETFL) = 0x802 (flags O_RDWR|O_NONBLOCK) 5092 17:05:24 fcntl64(4, F_SETFL, O_RDWR) = 0 5092 17:05:24 fcntl64(8, F_GETFL) = 0x2 (flags O_RDWR) 5092 17:05:24 fcntl64(8, F_SETFL, O_RDWR|O_NONBLOCK) = 0 5092 17:05:24 select(9, [8], NULL, NULL, {0, 0}) = 0 (Timeout) 5092 17:05:24 write(4, "250-knute4.aerioconnect.net Hell"..., 220) = 220 5092 17:05:24 read(8, 0xb8ec1810, 4096) = -1 EAGAIN (Resource temporarily unavailable) 5092 17:05:24 gettimeofday({1234659924, 604237}, NULL) = 0 5092 17:05:24 select(9, [8], NULL, [8], {300, 0}) = 1 (in [8], left {299, 970000}) 5092 17:05:24 gettimeofday({1234659924, 635080}, NULL) = 0 5092 17:05:24 read(8, "STARTTLS ", 4096) = 10 5092 17:05:24 fcntl64(4, F_GETFL) = 0x802 (flags O_RDWR|O_NONBLOCK) 5092 17:05:24 fcntl64(4, F_SETFL, O_RDWR) = 0 5092 17:05:24 write(4, "220 2.0.0 Ready to start TLS ", 30) = 30 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 brk(0xb8ef6000) = 0xb8ef6000 5092 17:05:24 read(8, "2631 2601 25431", 11) = 11 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 read(8, " 2275=q4341377270 D232FVa[323<n26=)363220"..., 170) = 170 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 write(4, "2631 J2 F31I227jT317{367373274vL245m+"..., 2143) = 2143 5092 17:05:24 read(8, "263116", 5) = 5 5092 17:05:24 read(8, "20 121 26&(}377370220337325g?244}3125y4"..., 262) = 262 5092 17:05:24 read(8, "2431 1", 5) = 5 5092 17:05:24 read(8, "1", 1) = 1 5092 17:05:24 read(8, "2631 000", 5) = 5 5092 17:05:24 read(8, "]246262335+27535347226k3)0/312bt317272_4217"..., 48) = 48 5092 17:05:24 write(4, "2431 112631 000203364d205W224$30M222+"..., 59) = 59 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2819, ...}) = 0 5092 17:05:24 send(3, "<22>Feb 14 17:05:24 sm-mta[5092]"..., 171, MSG_NOSIGNAL) = 171 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(8, {st_mode=S_IFSOCK|0777, st_size=0, ...}) = 0 5092 17:05:24 fstat64(4, {st_mode=S_IFSOCK|0777, st_size=0, ...}) = 0 5092 17:05:24 fcntl64(8, F_GETFL) = 0x2 (flags O_RDWR) 5092 17:05:24 fcntl64(8, F_SETFL, O_RDWR|O_NONBLOCK) = 0 5092 17:05:24 fstat64(8, {st_mode=S_IFSOCK|0777, st_size=0, ...}) = 0 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 read(8, "2731 000", 5) = 5 5092 17:05:24 read(8, "223303232Z20036364s33316y225R&A356?24735721". .., 48) = 48 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 lstat64("/var/lib/sendmail/sendmail.st", 0xbffb1b84) = -1 ENOENT (No such file or directory) 5092 17:05:24 lstat64("/", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0 5092 17:05:24 geteuid32() = 0 5092 17:05:24 lstat64("/var", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0 5092 17:05:24 geteuid32() = 0 5092 17:05:24 lstat64("/var/lib", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0 5092 17:05:24 geteuid32() = 0 5092 17:05:24 lstat64("/var/lib/sendmail", {st_mode=S_IFDIR|S_ISGID|0751, st_size=4096, ...}) = 0 5092 17:05:24 geteuid32() = 0 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 unlink("./dfn1F15OvP005092") = -1 ENOENT (No such file or directory) 5092 17:05:24 unlink("./qfn1F15OvP005092") = -1 ENOENT (No such file or directory) 5092 17:05:24 unlink("./xfn1F15OvP005092") = -1 ENOENT (No such file or directory) 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 fstat64(4, {st_mode=S_IFSOCK|0777, st_size=0, ...}) = 0 5092 17:05:24 fcntl64(4, F_GETFL) = 0x802 (flags O_RDWR|O_NONBLOCK) 5092 17:05:24 fcntl64(4, F_SETFL, O_RDWR) = 0 5092 17:05:24 fcntl64(8, F_GETFL) = 0x2 (flags O_RDWR) 5092 17:05:24 fcntl64(8, F_SETFL, O_RDWR|O_NONBLOCK) = 0 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 write(4, "27311 347B213`2vU@274v&266224[ T2345361"..., 261) = 261 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 read(8, 0xb8ec89b0, 5) = -1 EAGAIN (Resource temporarily unavailable) 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 select(9, [8], NULL, [8], {300, 0}) = 1 (in [8], left {299, 980000}) 5092 17:05:24 read(8, "2731 @", 5) = 5 5092 17:05:24 read(8, "377253322311264v225304T335K247357N~32534226". .., 64) = 64 5092 17:05:24 socket(PF_FILE, SOCK_STREAM, 0) = 5 5092 17:05:24 connect(5, {sa_family=AF_FILE, path="/var/run/saslauthd/mux"}, 110) = 0 5092 17:05:24 writev(5, [{" 5knute 10NaSO4H2O 4smtp ", 25}], 1) = 25 5092 17:05:24 read(5, " 2", 2) = 2 5092 17:05:24 read(5, "OK", 2) = 2 5092 17:05:24 close(5) = 0 5092 17:05:24 fcntl64(4, F_GETFL) = 0x802 (flags O_RDWR|O_NONBLOCK) 5092 17:05:24 fcntl64(4, F_SETFL, O_RDWR) = 0 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2819, ...}) = 0 5092 17:05:24 send(3, "<22>Feb 14 17:05:24 sm-mta[5092]"..., 127, MSG_NOSIGNAL) = 127 5092 17:05:24 fcntl64(8, F_GETFL) = 0x2 (flags O_RDWR) 5092 17:05:24 fcntl64(8, F_SETFL, O_RDWR|O_NONBLOCK) = 0 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 write(4, "2731 @21130b2703631036372#350=366R304$272"... , 69) = 69 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 read(8, 0xb8ec89b0, 5) = -1 EAGAIN (Resource temporarily unavailable) 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 select(9, [8], NULL, [8], {300, 0}) = 1 (in [8], left {299, 960000}) 5092 17:05:24 read(8, "2731 P", 5) = 5 5092 17:05:24 read(8, "LI331O177>27,13531q271A24120435033025i324"... , 80) = 80 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 stat64("/etc/mail/service.switch", {st_mode=S_IFREG|0644, st_size=149, ...}) = 0 5092 17:05:24 lstat64("/", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0 5092 17:05:24 geteuid32() = 0 5092 17:05:24 lstat64("/etc", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0 5092 17:05:24 geteuid32() = 0 5092 17:05:24 lstat64("/etc/mail", {st_mode=S_IFDIR|S_ISGID|0755, st_size=4096, ...}) = 0 5092 17:05:24 geteuid32() = 0 5092 17:05:24 open("/etc/mail/service.switch", O_RDONLY) = 5 5092 17:05:24 fstat64(5, {st_mode=S_IFREG|0644, st_size=149, ...}) = 0 5092 17:05:24 fcntl64(5, F_GETFL) = 0 (flags O_RDONLY) 5092 17:05:24 fstat64(5, {st_mode=S_IFREG|0644, st_size=149, ...}) = 0 5092 17:05:24 read(5, "# /etc/mail/nsswitch.conf # # Th"..., 4096) = 149 5092 17:05:24 read(5, "", 4096) = 0 5092 17:05:24 close(5) = 0 5092 17:05:24 open("/etc/hosts", O_RDONLY) = 5 5092 17:05:24 fstat64(5, {st_mode=S_IFREG|0644, st_size=373, ...}) = 0 5092 17:05:24 read(5, "127.0.0.1 localhost #216.240.58."..., 4096) = 373 5092 17:05:24 read(5, "", 4096) = 0 5092 17:05:24 close(5) = 0 5092 17:05:24 stat64("/etc/resolv.conf", {st_mode=S_IFREG|0644, st_size=91, ...}) = 0 5092 17:05:24 socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 5 5092 17:05:24 connect(5, {sa_family=AF_INET, sin_port=htons(53), sin_addr=inet_addr("207.181.0.10")}, 28) = 0 5092 17:05:24 fcntl64(5, F_GETFL) = 0x2 (flags O_RDWR) 5092 17:05:24 fcntl64(5, F_SETFL, O_RDWR|O_NONBLOCK) = 0 5092 17:05:24 gettimeofday({1234659924, 824128}, NULL) = 0 5092 17:05:24 poll([{fd=5, events=POLLOUT, revents=POLLOUT}], 1, 0) = 1 5092 17:05:24 send(5, "362<1 1 fknutejohnson3com 34"..., 34, MSG_NOSIGNAL) = 34 5092 17:05:24 poll([{fd=5, events=POLLIN, revents=POLLIN}], 1, 5000) = 1 5092 17:05:24 recvfrom(5, "362<204200 1 1 fknutejohnson3com 34"..., 8192, 0, {sa_family=AF_INET, sin_port=htons(53), sin_addr=inet_addr("207.181.0.10")}, [16]) = 90 5092 17:05:24 close(5) = 0 5092 17:05:24 stat64("/etc/resolv.conf", {st_mode=S_IFREG|0644, st_size=91, ...}) = 0 5092 17:05:24 socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 5 5092 17:05:24 connect(5, {sa_family=AF_INET, sin_port=htons(53), sin_addr=inet_addr("207.181.0.10")}, 28) = 0 5092 17:05:24 fcntl64(5, F_GETFL) = 0x2 (flags O_RDWR) 5092 17:05:24 fcntl64(5, F_SETFL, O_RDWR|O_NONBLOCK) = 0 5092 17:05:24 gettimeofday({1234659924, 858536}, NULL) = 0 5092 17:05:24 poll([{fd=5, events=POLLOUT, revents=POLLOUT}], 1, 0) = 1 5092 17:05:24 send(5, "2643551 1 fknutejohnson3com 1"..., 34, MSG_NOSIGNAL) = 34 5092 17:05:24 poll([{fd=5, events=POLLIN, revents=POLLIN}], 1, 5000) = 1 5092 17:05:24 recvfrom(5, "264355201200 1 1 fknutejohnson3com "..., 8192, 0, {sa_family=AF_INET, sin_port=htons(53), sin_addr=inet_addr("207.181.0.10")}, [16]) = 50 5092 17:05:24 close(5) = 0 5092 17:05:24 open("/etc/passwd", O_RDONLY|0x80000 /* O_??? */) = 5 5092 17:05:24 _llseek(5, 0, [0], SEEK_CUR) = 0 5092 17:05:24 fstat64(5, {st_mode=S_IFREG|0644, st_size=1562, ...}) = 0 5092 17:05:24 mmap2(NULL, 1562, PROT_READ, MAP_SHARED, 5, 0) = 0xb7ed6000 5092 17:05:24 _llseek(5, 1562, [1562], SEEK_SET) = 0 5092 17:05:24 fstat64(5, {st_mode=S_IFREG|0644, st_size=1562, ...}) = 0 5092 17:05:24 munmap(0xb7ed6000, 1562) = 0 5092 17:05:24 close(5) = 0 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 fcntl64(4, F_GETFL) = 0x802 (flags O_RDWR|O_NONBLOCK) 5092 17:05:24 fcntl64(4, F_SETFL, O_RDWR) = 0 5092 17:05:24 fcntl64(8, F_GETFL) = 0x2 (flags O_RDWR) 5092 17:05:24 fcntl64(8, F_SETFL, O_RDWR|O_NONBLOCK) = 0 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 write(4, "2731 P363|m217232301 303$303u36231425r312"..., 85) = 85 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 read(8, 0xb8ec89b0, 5) = -1 EAGAIN (Resource temporarily unavailable) 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 select(9, [8], NULL, [8], {300, 0}) = 1 (in [8], left {299, 980000}) 5092 17:05:24 read(8, "2731 @", 5) = 5 5092 17:05:24 read(8, "220I266_H207 054356354/D3473652323536k310f"..., 64) = 64 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 open("/etc/hosts", O_RDONLY) = 5 5092 17:05:24 fstat64(5, {st_mode=S_IFREG|0644, st_size=373, ...}) = 0 5092 17:05:24 read(5, "127.0.0.1 localhost #216.240.58."..., 4096) = 373 5092 17:05:24 read(5, "", 4096) = 0 5092 17:05:24 close(5) = 0 5092 17:05:24 stat64("/etc/resolv.conf", {st_mode=S_IFREG|0644, st_size=91, ...}) = 0 5092 17:05:24 socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 5 5092 17:05:24 connect(5, {sa_family=AF_INET, sin_port=htons(53), sin_addr=inet_addr("207.181.0.10")}, 28) = 0 5092 17:05:24 fcntl64(5, F_GETFL) = 0x2 (flags O_RDWR) 5092 17:05:24 fcntl64(5, F_SETFL, O_RDWR|O_NONBLOCK) = 0 5092 17:05:24 gettimeofday({1234659924, 885730}, NULL) = 0 5092 17:05:24 poll([{fd=5, events=POLLOUT, revents=POLLOUT}], 1, 0) = 1 5092 17:05:24 send(5, "23681 1 3wwwfknutejohnson3co"..., 38, MSG_NOSIGNAL) = 38 5092 17:05:24 poll([{fd=5, events=POLLIN, revents=POLLIN}], 1, 5000) = 1 5092 17:05:24 recvfrom(5, "2368204200 1 1 3wwwfknutejohnson3co"..., 8192, 0, {sa_family=AF_INET, sin_port=htons(53), sin_addr=inet_addr("207.181.0.10")}, [16]) = 94 5092 17:05:24 close(5) = 0 5092 17:05:24 stat64("/etc/resolv.conf", {st_mode=S_IFREG|0644, st_size=91, ...}) = 0 5092 17:05:24 socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 5 5092 17:05:24 connect(5, {sa_family=AF_INET, sin_port=htons(53), sin_addr=inet_addr("207.181.0.10")}, 28) = 0 5092 17:05:24 fcntl64(5, F_GETFL) = 0x2 (flags O_RDWR) 5092 17:05:24 fcntl64(5, F_SETFL, O_RDWR|O_NONBLOCK) = 0 5092 17:05:24 gettimeofday({1234659924, 908525}, NULL) = 0 5092 17:05:24 poll([{fd=5, events=POLLOUT, revents=POLLOUT}], 1, 0) = 1 5092 17:05:24 send(5, "h261 1 3wwwfknutejohnson3co"..., 38, MSG_NOSIGNAL) = 38 5092 17:05:24 poll([{fd=5, events=POLLIN, revents=POLLIN}], 1, 5000) = 1 5092 17:05:24 recvfrom(5, "h26201200 1 1 3wwwfknutejohnson3co"..., 8192, 0, {sa_family=AF_INET, sin_port=htons(53), sin_addr=inet_addr("207.181.0.10")}, [16]) = 54 5092 17:05:24 close(5) = 0 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 stat64("/etc/mail/aliases.db", {st_mode=S_IFREG|0644, st_size=12288, ...}) = 0 5092 17:05:24 lstat64("/", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0 5092 17:05:24 geteuid32() = 0 5092 17:05:24 lstat64("/etc", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0 5092 17:05:24 geteuid32() = 0 5092 17:05:24 lstat64("/etc/mail", {st_mode=S_IFDIR|S_ISGID|0755, st_size=4096, ...}) = 0 5092 17:05:24 geteuid32() = 0 5092 17:05:24 open("/etc/mail/aliases.db", O_RDONLY) = 5 5092 17:05:24 fstat64(5, {st_mode=S_IFREG|0644, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(5, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 stat64("/etc/mail/aliases.db", {st_mode=S_IFREG|0644, st_size=12288, ...}) = 0 5092 17:05:24 open("/etc/mail/aliases.db", O_RDONLY|O_LARGEFILE) = 9 5092 17:05:24 fcntl64(9, F_SETFD, FD_CLOEXEC) = 0 5092 17:05:24 read(9, " 1 a256 20 10"..., 512) = 512 5092 17:05:24 open("DB_CONFIG", O_RDONLY|O_LARGEFILE) = -1 ENOENT (No such file or directory) 5092 17:05:24 stat64("/var/tmp", {st_mode=S_IFDIR|S_ISVTX|0777, st_size=4096, ...}) = 0 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 gettimeofday({1234659924, 942599}, NULL) = 0 5092 17:05:24 open("/proc/stat", O_RDONLY) = 10 5092 17:05:24 fstat64(10, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0 5092 17:05:24 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7ed6000 5092 17:05:24 read(10, "cpu 40347 0 16568 57923844 2284"..., 1024) = 697 5092 17:05:24 read(10, "", 1024) = 0 5092 17:05:24 close(10) = 0 5092 17:05:24 munmap(0xb7ed6000, 4096) = 0 5092 17:05:24 mmap2(NULL, 159744, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb77ea000 5092 17:05:24 open("/etc/mail/aliases.db", O_RDONLY|O_LARGEFILE) = 10 5092 17:05:24 fcntl64(10, F_SETFD, FD_CLOEXEC) = 0 5092 17:05:24 fstat64(10, {st_mode=S_IFREG|0644, st_size=12288, ...}) = 0 5092 17:05:24 pread64(10, " 1 a256 20 10"..., 4096, 0) = 4096 5092 17:05:24 close(5) = 0 5092 17:05:24 fstat64(10, {st_mode=S_IFREG|0644, st_size=12288, ...}) = 0 5092 17:05:24 fstat64(10, {st_mode=S_IFREG|0644, st_size=12288, ...}) = 0 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 fcntl64(10, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(10, {st_mode=S_IFREG|0644, st_size=12288, ...}) = 0 5092 17:05:24 pread64(10, " 1 1 32 00617 "..., 4096, 4096) = 4096 5092 17:05:24 fcntl64(10, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 stat64("/etc/mail/aliases", {st_mode=S_IFREG|0640, st_size=422, ...}) = 0 5092 17:05:24 stat64("/etc/mail/aliases.db", {st_mode=S_IFREG|0644, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(10, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(10, {st_mode=S_IFREG|0644, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(10, F_SETLKW, {type=F_UNLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 umask(0) = 022 5092 17:05:24 umask(022) = 0 5092 17:05:24 stat64("./xfn1F15OvQ005092", 0xbffb37d4) = -1 ENOENT (No such file or directory) 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2819, ...}) = 0 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2819, ...}) = 0 5092 17:05:24 open("/proc/loadavg", O_RDONLY) = 5 5092 17:05:24 fstat64(5, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0 5092 17:05:24 read(5, "0.00 0.00 0.00 1/171 5092 ", 1024) = 26 5092 17:05:24 close(5) = 0 5092 17:05:24 fcntl64(4, F_GETFL) = 0x802 (flags O_RDWR|O_NONBLOCK) 5092 17:05:24 fcntl64(4, F_SETFL, O_RDWR) = 0 5092 17:05:24 fcntl64(8, F_GETFL) = 0x2 (flags O_RDWR) 5092 17:05:24 fcntl64(8, F_SETFL, O_RDWR|O_NONBLOCK) = 0 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 write(4, "2731 `a214227+=P31301]2572214256234J227314"..., 101) = 101 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 read(8, 0xb8ec89b0, 5) = -1 EAGAIN (Resource temporarily unavailable) 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 select(9, [8], NULL, [8], {300, 0}) = 1 (in [8], left {299, 980000}) 5092 17:05:24 read(8, "2731 ", 5) = 5 5092 17:05:24 read(8, "6362202341y33722305Df257433233332334203272".. ., 32) = 32 5092 17:05:24 fcntl64(4, F_GETFL) = 0x802 (flags O_RDWR|O_NONBLOCK) 5092 17:05:24 fcntl64(4, F_SETFL, O_RDWR) = 0 5092 17:05:24 fcntl64(8, F_GETFL) = 0x2 (flags O_RDWR) 5092 17:05:24 fcntl64(8, F_SETFL, O_RDWR|O_NONBLOCK) = 0 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 write(4, "2731 Pf(2072374345o24732237634337431u(f"..., 85) = 85 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 read(8, 0xb8ec89b0, 5) = -1 EAGAIN (Resource temporarily unavailable) 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 select(9, [8], NULL, [8], {3600, 0}) = 1 (in [8], left {3599, 960000}) 5092 17:05:25 read(8, "27312 ", 5) = 5 5092 17:05:25 read(8, "24210M!336343210264"306264312r(273332%yV304". .., 544) = 544 5092 17:05:25 time(NULL) = 1234659925 5092 17:05:25 umask(0) = 022 5092 17:05:25 umask(022) = 0 5092 17:05:25 stat64("./dfn1F15OvQ005092", 0xbffb2e34) = -1 ENOENT (No such file or directory) 5092 17:05:25 stat64("./dfn1F15OvQ005092", 0xbffb2fe4) = -1 ENOENT (No such file or directory) 5092 17:05:25 umask(0) = 022 5092 17:05:25 stat64("./dfn1F15OvQ005092", 0xbffb1e24) = -1 ENOENT (No such file or directory) 5092 17:05:25 lstat64(".", {st_mode=S_IFDIR|S_ISGID|0750, st_size=4096, ...}) = 0 5092 17:05:25 geteuid32() = 0 5092 17:05:25 stat64(".", {st_mode=S_IFDIR|S_ISGID|0750, st_size=4096, ...}) = 0 5092 17:05:25 open("./dfn1F15OvQ005092", O_RDWR|O_CREAT|O_EXCL, 0640) = 5 5092 17:05:25 fstat64(5, {st_mode=S_IFREG|0640, st_size=0, ...}) = 0 5092 17:05:25 fcntl64(5, F_SETLKW, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:25 umask(022) = 0 5092 17:05:25 lseek(5, 0, SEEK_SET) = 0 5092 17:05:25 write(5, "TEST3 -- Knute Johnson "The "..., 127) = 127 5092 17:05:25 fsync(5) = 0 5092 17:05:25 close(5) = 0 5092 17:05:25 time(NULL) = 1234659925 5092 17:05:25 stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2819, ...}) = 0 5092 17:05:25 send(3, "<22>Feb 14 17:05:25 sm-mta[5092]"..., 231, MSG_NOSIGNAL) = 231 5092 17:05:25 open("./dfn1F15OvQ005092", O_RDONLY) = 5 5092 17:05:25 open("./qfn1F15OvQ005092", O_WRONLY|O_CREAT|O_EXCL, 0640) = 11 5092 17:05:25 fcntl64(11, F_SETLK, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:25 fcntl64(11, F_GETFL) = 0x1 (flags O_WRONLY) 5092 17:05:25 fstat64(11, {st_mode=S_IFREG|0640, st_size=0, ...}) = 0 5092 17:05:25 write(11, "V8 T1234659924 K0 N0 P30491 Fbs "..., 986) = 986 5092 17:05:25 fsync(11) = 0 5092 17:05:25 fcntl64(4, F_GETFL) = 0x802 (flags O_RDWR|O_NONBLOCK) 5092 17:05:25 fcntl64(4, F_SETFL, O_RDWR) = 0 5092 17:05:25 open("/proc/loadavg", O_RDONLY) = 12 5092 17:05:25 fstat64(12, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0 5092 17:05:25 read(12, "0.00 0.00 0.00 1/171 5092 ", 1024) = 26 5092 17:05:25 close(12) = 0 5092 17:05:25 close(5) = 0 5092 17:05:25 close(11) = 0 5092 17:05:25 unlink("./xfn1F15OvQ005092") = -1 ENOENT (No such file or directory) 5092 17:05:25 time(NULL) = 1234659925 5092 17:05:25 write(4, "2731 Po245 3053533462751Ed2075"3341317364"..., 85) = 85 5092 17:05:25 close(9) = 0 5092 17:05:25 close(10) = 0 5092 17:05:25 munmap(0xb77ea000, 159744) = 0 5092 17:05:25 close(6) = 0 5092 17:05:25 close(7) = 0 5092 17:05:25 munmap(0xb7811000, 159744) = 0 5092 17:05:25 clone(child_stack=0, flags=CLONE_CHILD_CLEARTID|CLONE_CHILD_SETTID|SIGC HLD, child_tidptr=0xb78c26f8) = 5093 5093 17:05:25 rt_sigprocmask(SIG_UNBLOCK, [CHLD], <unfinished ...> 5092 17:05:25 time( <unfinished ...> 5093 17:05:25 <... rt_sigprocmask resumed> [], 8) = 0 5092 17:05:25 <... time resumed> NULL) = 1234659925 5093 17:05:25 rt_sigaction(SIGCHLD, {SIG_DFL}, {0xb7f11520, [], SA_RESTART}, 8) = 0 5093 17:05:25 alarm(0 <unfinished ...> 5092 17:05:25 fcntl64(8, F_GETFL <unfinished ...> 5093 17:05:25 <... alarm resumed> ) = 0 5092 17:05:25 <... fcntl64 resumed> ) = 0x2 (flags O_RDWR) 5093 17:05:25 rt_sigprocmask(SIG_BLOCK, [ALRM], [], 8) = 0 5092 17:05:25 fcntl64(8, F_SETFL, O_RDWR|O_NONBLOCK) = 0 5093 17:05:25 rt_sigprocmask(SIG_UNBLOCK, [ALRM], [ALRM], 8) = 0 5092 17:05:25 time(NULL) = 1234659925 5093 17:05:25 rt_sigaction(SIGINT, {SIG_IGN}, <unfinished ...> 5092 17:05:25 read(8, <unfinished ...> 5093 17:05:25 <... rt_sigaction resumed> {SIG_IGN}, 8) = 0 5092 17:05:25 <... read resumed> 0xb8ec89b0, 5) = -1 EAGAIN (Resource temporarily unavailable) 5093 17:05:25 rt_sigaction(SIGQUIT, {SIG_IGN}, <unfinished ...> 5092 17:05:25 time( <unfinished ...> 5093 17:05:25 <... rt_sigaction resumed> {SIG_IGN}, 8) = 0 5092 17:05:25 <... time resumed> NULL) = 1234659925 5093 17:05:25 rt_sigprocmask(SIG_BLOCK, NULL, [], 8) = 0 5092 17:05:25 select(9, [8], NULL, [8], {3600, 0} <unfinished ...> 5093 17:05:25 rt_sigprocmask(SIG_BLOCK, [ALRM], [], 8) = 0 5093 17:05:25 time(NULL) = 1234659925 5093 17:05:25 rt_sigaction(SIGALRM, {0xb7f9e170, [], SA_RESTART}, {0xb7f9e170, [], SA_RESTART}, 8) = 0 5093 17:05:25 alarm(3600) = 0 5093 17:05:25 rt_sigprocmask(SIG_UNBLOCK, [ALRM], [ALRM], 8) = 0 5093 17:05:25 close(8) = 0 5093 17:05:25 rt_sigprocmask(SIG_BLOCK, [ALRM], [], 8) = 0 5093 17:05:25 rt_sigprocmask(SIG_UNBLOCK, [ALRM], [ALRM], 8) = 0 5093 17:05:25 alarm(0) = 3600 5093 17:05:25 close(0) = 0 5093 17:05:25 open("/dev/null", O_RDONLY) = 0 5093 17:05:25 close(4) = 0 5093 17:05:25 open("/dev/null", O_WRONLY) = 4 5093 17:05:25 dup2(4, 1) = 1 5093 17:05:25 dup2(4, 2) = 2 5093 17:05:25 close(4) = 0 5093 17:05:25 fstat64(0, {st_mode=S_IFCHR|0666, st_rdev=makedev(1, 3), ...}) = 0 5093 17:05:25 fstat64(1, {st_mode=S_IFCHR|0666, st_rdev=makedev(1, 3), ...}) = 0 5093 17:05:25 fstat64(2, {st_mode=S_IFCHR|0666, st_rdev=makedev(1, 3), ...}) = 0 5093 17:05:25 umask(0) = 022 5093 17:05:25 umask(022) = 0 5093 17:05:25 stat64("./xfn1F15OvQ005092", 0xbffb3284) = -1 ENOENT (No such file or directory) 5093 17:05:25 open("./qfn1F15OvQ005092", O_RDWR) = 4 5093 17:05:25 fcntl64(4, F_SETLK, {type=F_WRLCK, whence=SEEK_SET, start=0, len=0}) = 0 5093 17:05:25 stat64("./qfn1F15OvQ005092", {st_mode=S_IFREG|0640, st_size=986, ...}) = 0 5093 17:05:25 fstat64(4, {st_mode=S_IFREG|0640, st_size=986, ...}) = 0 5093 17:05:25 geteuid32() = 0 5093 17:05:25 geteuid32() = 0 5093 17:05:25 time(NULL) = 1234659925 5093 17:05:25 time(NULL) = 1234659925 5093 17:05:25 stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2819, ...}) = 0 5093 17:05:25 time(NULL) = 1234659925 5093 17:05:25 stat64("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2819, ...}) = 0 5093 17:05:25 open("/proc/loadavg", O_RDONLY) = 5 5093 17:05:25 fstat64(5, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0 5093 17:05:25 read(5, "0.00 0.00 0.00 1/172 5093 ", 1024) = 26 5093 17:05:25 close(5) = 0 5093 17:05:25 fstat64(4, {st_mode=S_IFREG|0640, st_size=986, ...}) = 0 5093 17:05:25 read(4, "V8 T1234659924 K0 N0 P30491 Fbs "..., 4096) = 986 5093 17:05:25 time(NULL) = 1234659925 5093 17:05:25 time(NULL) = 1234659925 5093 17:05:25 open("/etc/passwd", O_RDONLY|0x80000 /* O_??? */) = 5 5093 17:05:25 _llseek(5, 0, [0], SEEK_CUR) = 0 5093 17:05:25 fstat64(5, {st_mode=S_IFREG|0644, st_size=1562, ...}) = 0 5093 17:05:25 mmap2(NULL, 1562, PROT_READ, MAP_SHARED, 5, 0) = 0xb7ed6000 5093 17:05:25 _llseek(5 |
Sendmail slow?
Smoot Carl-Mitchell wrote:
> You might try running strace on the listening SMTP process with timer > option to see where the delay is creeping in. Something like > > sudo strace -f -p <pid> -o <output_file> -t > > You need the -f to pick up the forked processes as the listening > sendmail handles the connections. The -t will give you timestamps on > each system call. I sent the whole output but it was too big and rejected. Anyway, this shows a 5 second gap but I don't know what it really means. If you can decipher it, great! Thanks, 5092 17:05:19 stat64("/etc/resolv.conf", {st_mode=S_IFREG|0644, st_size=91, ...}) = 0 5092 17:05:19 socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 5 5092 17:05:19 connect(5, {sa_family=AF_INET, sin_port=htons(53), sin_addr=inet_addr("207.181.0.10")}, 28) = 0 5092 17:05:19 fcntl64(5, F_GETFL) = 0x2 (flags O_RDWR) 5092 17:05:19 fcntl64(5, F_SETFL, O_RDWR|O_NONBLOCK) = 0 5092 17:05:19 gettimeofday({1234659919, 562536}, NULL) = 0 5092 17:05:19 poll([{fd=5, events=POLLOUT, revents=POLLOUT}], 1, 0) = 1 5092 17:05:19 send(5, "331S1 1 6knute2faerioconnect"..., 41, MSG_NOSIGNAL) = 41 5092 17:05:19 poll([{fd=5, events=POLLIN, revents=POLLIN}], 1, 5000) = 1 5092 17:05:19 ioctl(5, FIONREAD, [57]) = 0 5092 17:05:19 recvfrom(5, "331S205200 1 1 6knute2faerioconnect"..., 1024, 0, {sa_family=AF_INET, sin_port=htons(53), sin_addr=inet_addr("207.181.0.10")}, [16]) = 57 5092 17:05:19 close(5) = 0 5092 17:05:19 getsockname(8, {sa_family=AF_INET, sin_port=htons(587), sin_addr=inet_addr("216.240.58.140")}, [16]) = 0 5092 17:05:19 open("/etc/services", O_RDONLY|0x80000 /* O_??? */) = 5 5092 17:05:19 fstat64(5, {st_mode=S_IFREG|0644, st_size=18449, ...}) = 0 5092 17:05:19 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7ed6000 5092 17:05:19 read(5, "# Network services, Internet sty"..., 4096) = 4096 5092 17:05:19 close(5) = 0 5092 17:05:19 munmap(0xb7ed6000, 4096) = 0 5092 17:05:19 rt_sigprocmask(SIG_BLOCK, NULL, [], 8) = 0 5092 17:05:19 rt_sigprocmask(SIG_BLOCK, [ALRM], [], 8) = 0 5092 17:05:19 time(NULL) = 1234659919 5092 17:05:19 rt_sigaction(SIGALRM, {0xb7f9e170, [], SA_RESTART}, {0xb7f9e170, [], SA_RESTART}, 8) = 0 5092 17:05:19 alarm(5) = 0 5092 17:05:19 rt_sigprocmask(SIG_UNBLOCK, [ALRM], [ALRM], 8) = 0 5092 17:05:19 socket(PF_INET, SOCK_STREAM, IPPROTO_IP) = 5 5092 17:05:19 bind(5, {sa_family=AF_INET, sin_port=htons(0), sin_addr=inet_addr("216.240.58.140")}, 16) = 0 5092 17:05:19 connect(5, {sa_family=AF_INET, sin_port=htons(113), sin_addr=inet_addr("216.240.58.138")}, 16 <unfinished ...> 16288 17:05:24 <... select resumed> ) = 0 (Timeout) 16288 17:05:24 rt_sigprocmask(SIG_BLOCK, [ALRM], [], 8) = 0 16288 17:05:24 time(NULL) = 1234659924 16288 17:05:24 time(NULL) = 1234659924 16288 17:05:24 open("/proc/loadavg", O_RDONLY) = 8 16288 17:05:24 fstat64(8, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0 16288 17:05:24 read(8, "0.00 0.00 0.00 2/171 5092 ", 1024) = 26 16288 17:05:24 close(8) = 0 16288 17:05:24 time(NULL) = 1234659924 16288 17:05:24 time(NULL) = 1234659924 16288 17:05:24 open("/proc/loadavg", O_RDONLY) = 8 16288 17:05:24 fstat64(8, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0 16288 17:05:24 read(8, "0.00 0.00 0.00 2/171 5092 ", 1024) = 26 16288 17:05:24 close(8) = 0 16288 17:05:24 time(NULL) = 1234659924 16288 17:05:24 rt_sigprocmask(SIG_UNBLOCK, [ALRM], [ALRM], 8) = 0 16288 17:05:24 select(7, [4 5 6], NULL, NULL, {5, 0} <unfinished ...> 5092 17:05:24 <... connect resumed> ) = ? ERESTARTSYS (To be restarted) 5092 17:05:24 --- SIGALRM (Alarm clock) @ 0 (0) --- 5092 17:05:24 alarm(0) = 0 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 alarm(580) = 0 5092 17:05:24 rt_sigprocmask(SIG_SETMASK, [], NULL, 8) = 0 5092 17:05:24 close(5) = 0 5092 17:05:24 getsockopt(8, SOL_IP, IP_OPTIONS, "", [0]) = 0 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 open("/proc/loadavg", O_RDONLY) = 5 5092 17:05:24 fstat64(5, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0 5092 17:05:24 read(5, "0.00 0.00 0.00 1/171 5092 ", 1024) = 26 5092 17:05:24 close(5) = 0 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 stat64("/etc/mail/access.db", {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 lstat64("/", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0 5092 17:05:24 geteuid32() = 0 5092 17:05:24 lstat64("/etc", {st_mode=S_IFDIR|0755, st_size=4096, ...}) = 0 5092 17:05:24 geteuid32() = 0 5092 17:05:24 lstat64("/etc/mail", {st_mode=S_IFDIR|S_ISGID|0755, st_size=4096, ...}) = 0 5092 17:05:24 geteuid32() = 0 5092 17:05:24 open("/etc/mail/access.db", O_RDONLY) = 5 5092 17:05:24 fstat64(5, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(5, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 stat64("/etc/mail/access.db", {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 open("/etc/mail/access.db", O_RDONLY|O_LARGEFILE) = 6 5092 17:05:24 fcntl64(6, F_SETFD, FD_CLOEXEC) = 0 5092 17:05:24 read(6, " 1 a256 20 10"..., 512) = 512 5092 17:05:24 open("DB_CONFIG", O_RDONLY|O_LARGEFILE) = -1 ENOENT (No such file or directory) 5092 17:05:24 stat64("/var/tmp", {st_mode=S_IFDIR|S_ISVTX|0777, st_size=4096, ...}) = 0 5092 17:05:24 time(NULL) = 1234659924 5092 17:05:24 gettimeofday({1234659924, 571143}, NULL) = 0 5092 17:05:24 open("/proc/stat", O_RDONLY) = 7 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0444, st_size=0, ...}) = 0 5092 17:05:24 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7ed6000 5092 17:05:24 read(7, "cpu 40339 0 16567 57923774 2284"..., 1024) = 697 5092 17:05:24 read(7, "", 1024) = 0 5092 17:05:24 close(7) = 0 5092 17:05:24 munmap(0xb7ed6000, 4096) = 0 5092 17:05:24 mmap2(NULL, 159744, PROT_READ|PROT_WRITE, MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7811000 5092 17:05:24 open("/etc/mail/access.db", O_RDONLY|O_LARGEFILE) = 7 5092 17:05:24 fcntl64(7, F_SETFD, FD_CLOEXEC) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 pread64(7, " 1 a256 20 10"..., 4096, 0) = 4096 5092 17:05:24 close(5) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 5092 17:05:24 fcntl64(7, F_SETLKW, {type=F_RDLCK, whence=SEEK_SET, start=0, len=0}) = 0 5092 17:05:24 fstat64(7, {st_mode=S_IFREG|0640, st_size=12288, ...}) = 0 -- Knute Johnson "The urge to save humanity is almost always a false front for the urge to rule." H.L. Mencken -- ubuntu-users mailing list ubuntu-users@lists.ubuntu.com Modify settings or unsubscribe at: https://lists.ubuntu.com/mailman/listinfo/ubuntu-users |
Sendmail slow?
On Sat, 2009-02-14 at 17:15 -0800, Knute Johnson wrote:
> Smoot Carl-Mitchell wrote: > > You might try running strace on the listening SMTP process with timer > > option to see where the delay is creeping in. Something like > > > > sudo strace -f -p <pid> -o <output_file> -t > > > > You need the -f to pick up the forked processes as the listening > > sendmail handles the connections. The -t will give you timestamps on > > each system call. > > I sent the whole output but it was too big and rejected. Anyway, this > shows a 5 second gap but I don't know what it really means. If you can > decipher it, great! > > Thanks, > Here is the blow by blow. Read /etc/resolv.conf. Likely getting the names of your nameservers. > 5092 17:05:19 stat64("/etc/resolv.conf", {st_mode=S_IFREG|0644, > st_size=91, ...}) = 0 > 5092 17:05:19 socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 5 Connect to 207.181.0.10 (would not happen to be one of your nameservers?) The connection descriptor is 5. > 5092 17:05:19 connect(5, {sa_family=AF_INET, sin_port=htons(53), > sin_addr=inet_addr("207.181.0.10")}, 28) = 0 > 5092 17:05:19 poll([{fd=5, events=POLLOUT, revents=POLLOUT}], 1, 0) = 1 Send a request to descriptor 5. This does look like a DNS request. > 5092 17:05:19 send(5, > "331S1 1 6knute2faerioconnect"..., 41, MSG_NOSIGNAL) = 41 > 5092 17:05:19 poll([{fd=5, events=POLLIN, revents=POLLIN}], 1, 5000) = 1 > 5092 17:05:19 ioctl(5, FIONREAD, [57]) = 0 Read the response. > 5092 17:05:19 recvfrom(5, > "331S205200 1 1 6knute2faerioconnect"..., 1024, 0, > {sa_family=AF_INET, sin_port=htons(53), > sin_addr=inet_addr("207.181.0.10")}, [16]) = 57 Close the connection. > 5092 17:05:19 close(5) = 0 Now set up a socket on port 587 which is the submit port. This is using descriptor 8. What is host 216.240.58.140, BTW? > 5092 17:05:19 getsockname(8, {sa_family=AF_INET, sin_port=htons(587), > sin_addr=inet_addr("216.240.58.140")}, [16]) = 0 Read /etc/services. > 5092 17:05:19 open("/etc/services", O_RDONLY|0x80000 /* O_??? */) = 5 > 5092 17:05:19 fstat64(5, {st_mode=S_IFREG|0644, st_size=18449, ...}) = 0 > 5092 17:05:19 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, > MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7ed6000 > 5092 17:05:19 read(5, "# Network services, Internet sty"..., 4096) = 4096 > 5092 17:05:19 close(5) = 0 > 5092 17:05:19 munmap(0xb7ed6000, 4096) = 0 > 5092 17:05:19 rt_sigprocmask(SIG_BLOCK, NULL, [], 8) = 0 > 5092 17:05:19 rt_sigprocmask(SIG_BLOCK, [ALRM], [], 8) = 0 > 5092 17:05:19 time(NULL) = 1234659919 > 5092 17:05:19 rt_sigaction(SIGALRM, {0xb7f9e170, [], SA_RESTART}, > {0xb7f9e170, [], SA_RESTART}, 8) = 0 Set an alarms for 5 seconds. > 5092 17:05:19 alarm(5) = 0 > 5092 17:05:19 rt_sigprocmask(SIG_UNBLOCK, [ALRM], [ALRM], 8) = 0 New socket on descriptor 5. > 5092 17:05:19 socket(PF_INET, SOCK_STREAM, IPPROTO_IP) = 5 Bind it to the address 216.240.58.140 which I assume is one of your local interface addresses. > 5092 17:05:19 bind(5, {sa_family=AF_INET, sin_port=htons(0), > sin_addr=inet_addr("216.240.58.140")}, 16) = 0 And connect on descriptor 5. This is connecting on port 113 which looking at /etc/services is the auth port. This trying to authenticate the sender of the email using the AUTH protocol. See RFC1413. Your sendmail is trying to use AUTH to detemine the identity of the sender. You can disable this in your sendmail.mc file by adding define(`confTO_IDENT', `0s')dnl to your sendmail.mc and yor submit.mc file after the include for debian-mta or debian-msp and redoing the make in /etc/mail. > 5092 17:05:19 connect(5, {sa_family=AF_INET, sin_port=htons(113), > sin_addr=inet_addr("216.240.58.138")}, 16 <unfinished ...> The alarm is triggered after 5 seconds which is the delay you are seeing. So the AUTH protocol is the source of the delay. > 16288 17:05:24 <... select resumed> ) = 0 (Timeout) -- Smoot Carl-Mitchell Computer Systems and Network Consultant smoot@tic.com +1 480 922 7313 cell: +1 602 421 9005 -- ubuntu-users mailing list ubuntu-users@lists.ubuntu.com Modify settings or unsubscribe at: https://lists.ubuntu.com/mailman/listinfo/ubuntu-users |
Sendmail slow?
Smoot Carl-Mitchell wrote:
> > The alarm is triggered after 5 seconds which is the delay you are > seeing. So the AUTH protocol is the source of the delay. >> 16288 17:05:24 <... select resumed> ) = 0 (Timeout) > good ol' Auth/Ident, gets them every time. You should consider configuring firewalls to Reject Ident rather than drop to avoid this potential problem with other servers you communicate with over the net. Unfortunately, doing so does mean you aren't stealthed anymore. -- ubuntu-users mailing list ubuntu-users@lists.ubuntu.com Modify settings or unsubscribe at: https://lists.ubuntu.com/mailman/listinfo/ubuntu-users |
Sendmail slow?
Smoot Carl-Mitchell wrote:
> On Sat, 2009-02-14 at 17:15 -0800, Knute Johnson wrote: >> Smoot Carl-Mitchell wrote: >>> You might try running strace on the listening SMTP process with timer >>> option to see where the delay is creeping in. Something like >>> >>> sudo strace -f -p <pid> -o <output_file> -t >>> >>> You need the -f to pick up the forked processes as the listening >>> sendmail handles the connections. The -t will give you timestamps on >>> each system call. >> I sent the whole output but it was too big and rejected. Anyway, this >> shows a 5 second gap but I don't know what it really means. If you can >> decipher it, great! >> >> Thanks, >> > > Here is the blow by blow. > > Read /etc/resolv.conf. Likely getting the names of your nameservers. >> 5092 17:05:19 stat64("/etc/resolv.conf", {st_mode=S_IFREG|0644, >> st_size=91, ...}) = 0 >> 5092 17:05:19 socket(PF_INET, SOCK_DGRAM, IPPROTO_IP) = 5 > > Connect to 207.181.0.10 (would not happen to be one of your > nameservers?) The connection descriptor is 5. >> 5092 17:05:19 connect(5, {sa_family=AF_INET, sin_port=htons(53), >> sin_addr=inet_addr("207.181.0.10")}, 28) = 0 >> 5092 17:05:19 poll([{fd=5, events=POLLOUT, revents=POLLOUT}], 1, 0) = 1 > > Send a request to descriptor 5. This does look like a DNS request. >> 5092 17:05:19 send(5, >> "331S1 1 6knute2faerioconnect"..., 41, MSG_NOSIGNAL) = 41 >> 5092 17:05:19 poll([{fd=5, events=POLLIN, revents=POLLIN}], 1, 5000) = 1 >> 5092 17:05:19 ioctl(5, FIONREAD, [57]) = 0 > > Read the response. >> 5092 17:05:19 recvfrom(5, >> "331S205200 1 1 6knute2faerioconnect"..., 1024, 0, >> {sa_family=AF_INET, sin_port=htons(53), >> sin_addr=inet_addr("207.181.0.10")}, [16]) = 57 > > Close the connection. >> 5092 17:05:19 close(5) = 0 > > Now set up a socket on port 587 which is the submit port. This is using > descriptor 8. What is host 216.240.58.140, BTW? >> 5092 17:05:19 getsockname(8, {sa_family=AF_INET, sin_port=htons(587), >> sin_addr=inet_addr("216.240.58.140")}, [16]) = 0 > > Read /etc/services. >> 5092 17:05:19 open("/etc/services", O_RDONLY|0x80000 /* O_??? */) = 5 >> 5092 17:05:19 fstat64(5, {st_mode=S_IFREG|0644, st_size=18449, ...}) = 0 >> 5092 17:05:19 mmap2(NULL, 4096, PROT_READ|PROT_WRITE, >> MAP_PRIVATE|MAP_ANONYMOUS, -1, 0) = 0xb7ed6000 >> 5092 17:05:19 read(5, "# Network services, Internet sty"..., 4096) = 4096 >> 5092 17:05:19 close(5) = 0 > > >> 5092 17:05:19 munmap(0xb7ed6000, 4096) = 0 >> 5092 17:05:19 rt_sigprocmask(SIG_BLOCK, NULL, [], 8) = 0 >> 5092 17:05:19 rt_sigprocmask(SIG_BLOCK, [ALRM], [], 8) = 0 >> 5092 17:05:19 time(NULL) = 1234659919 >> 5092 17:05:19 rt_sigaction(SIGALRM, {0xb7f9e170, [], SA_RESTART}, >> {0xb7f9e170, [], SA_RESTART}, 8) = 0 > > Set an alarms for 5 seconds. >> 5092 17:05:19 alarm(5) = 0 >> 5092 17:05:19 rt_sigprocmask(SIG_UNBLOCK, [ALRM], [ALRM], 8) = 0 > > New socket on descriptor 5. >> 5092 17:05:19 socket(PF_INET, SOCK_STREAM, IPPROTO_IP) = 5 > > Bind it to the address 216.240.58.140 which I assume is one of your > local interface addresses. >> 5092 17:05:19 bind(5, {sa_family=AF_INET, sin_port=htons(0), >> sin_addr=inet_addr("216.240.58.140")}, 16) = 0 > > And connect on descriptor 5. This is connecting on port 113 which > looking at /etc/services is the auth port. This trying to authenticate > the sender of the email using the AUTH protocol. See RFC1413. Your > sendmail is trying to use AUTH to detemine the identity of the sender. > You can disable this in your sendmail.mc file by adding > > define(`confTO_IDENT', `0s')dnl > > to your sendmail.mc and yor submit.mc file after the include for > debian-mta or debian-msp and redoing the make in /etc/mail. > >> 5092 17:05:19 connect(5, {sa_family=AF_INET, sin_port=htons(113), >> sin_addr=inet_addr("216.240.58.138")}, 16 <unfinished ...> > > > The alarm is triggered after 5 seconds which is the delay you are > seeing. So the AUTH protocol is the source of the delay. >> 16288 17:05:24 <... select resumed> ) = 0 (Timeout) > That's it! No delay at all now. Thanks very much for helping me figure this out. -- Knute Johnson "The urge to save humanity is almost always a false front for the urge to rule." H.L. Mencken -- ubuntu-users mailing list ubuntu-users@lists.ubuntu.com Modify settings or unsubscribe at: https://lists.ubuntu.com/mailman/listinfo/ubuntu-users |
| All times are GMT. The time now is 02:39 PM. |
VBulletin, Copyright ©2000 - 2013, Jelsoft Enterprises Ltd.
Content Relevant URLs by vBSEO ©2007, Crawlability, Inc.