From: (no name) (no email)
Date: Fri Nov 02 2007 - 10:40:27 EDT
>> Doing an strace revealed that the permission denied error message from
>> Postfix was completely bogus, in fact it communicated fine through the
>> socket.
>
> The warning:
>
> warning: connect to Milter service
> unix:/var/spool/MIMEDefang/mimedefang.sock: Permission denied
>
> was reported BEFORE the socket was opened.
You'd think so. But according to strace the socket opened fine and
Postfix wrote about 20 characters to it. At least that's how I
interpret it.
Here's a snippet of the strace output - I've marked interesting lines
with asterix:
=========
sendto(7, "<22>Nov 1 21:33:23 postfix/smtp"..., 114, MSG_NOSIGNAL,
NULL, 0) = 114
* socket(PF_FILE, SOCK_STREAM, 0) = 17
fcntl(17, F_GETFL) = 0x2 (flags O_RDWR)
fcntl(17, F_SETFL, O_RDWR|O_NONBLOCK) = 0
* connect(17, {sa_family=AF_FILE,
path="/var/run/defang/mimedefang.sock"}, 110) = 0
fcntl(17, F_GETFL) = 0x802 (flags O_RDWR|O_NONBLOCK)
fcntl(17, F_SETFL, O_RDWR) = 0
gettimeofday({1193949203, 381172}, NULL) = 0
time([1193949203]) = 1193949203
stat("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2134, ...}) = 0
stat("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2134, ...}) = 0
stat("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2134, ...}) = 0
sendto(7, "<22>Nov 1 21:33:23 postfix/smtp"..., 73, MSG_NOSIGNAL, NULL, 0) = 73
time([1193949203]) = 1193949203
stat("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2134, ...}) = 0
stat("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2134, ...}) = 0
stat("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2134, ...}) = 0
sendto(7, "<22>Nov 1 21:33:23 postfix/smtp"..., 147, MSG_NOSIGNAL,
NULL, 0) = 147
time([1193949203]) = 1193949203
stat("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2134, ...}) = 0
stat("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2134, ...}) = 0
stat("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2134, ...}) = 0
sendto(7, "<22>Nov 1 21:33:23 postfix/smtp"..., 166, MSG_NOSIGNAL,
NULL, 0) = 166
select(18, NULL, [17], [17], {30, 0}) = 1 (out [17], left {30, 0})
* write(17, "\0\0\0\rO\0\0\0\2\0\0\0=\0\0\0\177", 17) = 17
gettimeofday({1193949203, 409834}, NULL) = 0
select(18, [17], NULL, [17], {30, 0}) = 1 (in [17], left {30, 0})
* read(17, "", 4096) = 0
time([1193949203]) = 1193949203
stat("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2134, ...}) = 0
stat("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2134, ...}) = 0
stat("/etc/localtime", {st_mode=S_IFREG|0644, st_size=2134, ...}) = 0
* sendto(7, "<20>Nov 1 21:33:23 postfix/smtp"..., 148, MSG_NOSIGNAL,
NULL, 0) = 148
* close(17) = 0
======
Then again, maybe I wasn't paying enough attention. The error message
could have been different between me seeing it initially, and me
running the strace to diagnose it, I guess.
|
|
|