Linux Archive

Linux Archive (http://www.linux-archive.org/)
-   Ubuntu User (http://www.linux-archive.org/ubuntu-user/)
-   -   Sendmail slow? (http://www.linux-archive.org/ubuntu-user/245191-sendmail-slow.html)

Knute Johnson 02-13-2009 09:48 PM

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

Smoot Carl-Mitchell 02-14-2009 12:15 AM

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

Knute Johnson 02-14-2009 02:52 AM

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

Knute Johnson 02-14-2009 02:57 AM

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

Smoot Carl-Mitchell 02-14-2009 06:01 PM

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

Knute Johnson 02-15-2009 12:12 AM

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,
"23720110313802580324003216"..., 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,
"23720205200110313802580324003"..., 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,
"331S116knute2faerioconnect"..., 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,
"331S205200116knute2faerioconnect"..., 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,
"1a256 2010"..., 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,
"1a256 2010"..., 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,
"113231716"..., 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,
"12"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, "242613Tj227I",
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)=[{"0242Tj227I344232102003761"...,
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)=[{"@242Tj227I34423
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)=[{"2432Tj227I34423124"...,
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, "242613Tj227I",
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)=[{"0242Tj227I344232102003761"...,
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)=[{"@242Tj227I34423
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)=[{"2432Tj227I34423124"...,
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, "2631260125431", 11) = 11
5092 17:05:24 time(NULL) = 1234659924
5092 17:05:24 read(8,
"2275=q4341377270D232FVa[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,
"2631J2F31I227jT317{367373274vL245m+"..., 2143) = 2143
5092 17:05:24 read(8, "263116", 5) = 5
5092 17:05:24 read(8,
"2012126&(}377370220337325g?244}3125y4"..., 262) = 262
5092 17:05:24 read(8, "24311", 5) = 5
5092 17:05:24 read(8, "1", 1) = 1
5092 17:05:24 read(8, "2631000", 5) = 5
5092 17:05:24 read(8,
"]246262335+27535347226k3)0/312bt317272_4217"..., 48) = 48
5092 17:05:24 write(4,
"2431112631000203364d205W224$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, "2731000", 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,
"27311347B213`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, [{"5knute10NaSO4H2O4smtp", 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, "2731P", 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<11fknutejohnson3com34"..., 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<20420011fknutejohnson3com34"..., 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,
"26435511fknutejohnson3com1"..., 34,
MSG_NOSIGNAL) = 34
5092 17:05:24 poll([{fd=5, events=POLLIN, revents=POLLIN}], 1, 5000) = 1
5092 17:05:24 recvfrom(5,
"26435520120011fknutejohnson3com"..., 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,
"2731P363|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_H207054356354/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,
"2368113wwwfknutejohnson3co"..., 38, MSG_NOSIGNAL)
= 38
5092 17:05:24 poll([{fd=5, events=POLLIN, revents=POLLIN}], 1, 5000) = 1
5092 17:05:24 recvfrom(5,
"2368204200113wwwfknutejohnson3co"..., 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,
"h26113wwwfknutejohnson3co"..., 38, MSG_NOSIGNAL) = 38
5092 17:05:24 poll([{fd=5, events=POLLIN, revents=POLLIN}], 1, 5000) = 1
5092 17:05:24 recvfrom(5,
"h26201200113wwwfknutejohnson3co"..., 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,
"1a256 2010"..., 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,
"1a256 2010"..., 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,
"113200617
"..., 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,
"2731Pf(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,
"2731Po245 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

Knute Johnson 02-15-2009 12:15 AM

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,
"331S116knute2faerioconnect"..., 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,
"331S205200116knute2faerioconnect"..., 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,
"1a256 2010"..., 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,
"1a256 2010"..., 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

Smoot Carl-Mitchell 02-15-2009 01:34 AM

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,
> "331S116knute2faerioconnect"..., 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,
> "331S205200116knute2faerioconnect"..., 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

Rashkae 02-15-2009 02:00 AM

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

Knute Johnson 02-15-2009 03:34 AM

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,
>> "331S116knute2faerioconnect"..., 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,
>> "331S205200116knute2faerioconnect"..., 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 01:12 PM.

VBulletin, Copyright ©2000 - 2014, Jelsoft Enterprises Ltd.
Content Relevant URLs by vBSEO ©2007, Crawlability, Inc.