OSEC

Neohapsis is currently accepting applications for employment. For more information, please visit our website www.neohapsis.com or email hr@neohapsis.com
 
Re: Intermittent "cannot update mailbox"

From: John Reddy (john.reddygmail.com)
Date: Mon Dec 11 2006 - 11:08:53 CST


On 12/8/06, Wietse Venema <wietseporcupine.org> wrote:
> John Reddy:
> > Dec 7 09:42:05 solaris-host local[18332]: [ID 197553 mail.info]
> > D35C3445C: to=<bubbasolaris-host.sample.com>, relay=local,
> > delay=0.17, delays=0.09/0/0/0.08, dsn=5.2.0, status=bounced (cannot
> > update mailbox /var/mail/bubba for user bubba. cannot open file:
> > Permission denied)
>
> This happens while opening an existing mailbox file.
>
> > Dec 7 10:52:26 solaris-host local[20775]: [ID 197553 mail.info]
> > 670C9445D: to=<jacksolaris-host.sample.com>, relay=local, delay=15,
> > delays=8.9/0/0/6.5, dsn=5.2.0, status=bounced (cannot update mailbox
> > /var/mail/jack for user jack. cannot open file: Permission denied)
>
> This happens after opening, while appending to the file.
>
> > Does anyone have any advice on how I might get better details about
> > why postfix is having trouble accessing the file?
>
> "Permission denied" is what the kernel says in response to an
> open(2) or write(2) request. Postfix just delivers the bad news.
>
> To find out why the kernel says "Permission denied" we need to know
> what Postfix is asking the kernel to write to. Is this a remote
> file system? What protocol is being used?

/var/mail in this system is a locally attached storage array running
UFS. Over the weekend, I set soft_bounce=yes, and mail has
successfully been delivered. Here's a sample:

Dec 11 10:21:07 solaris-host smtpd[29984]: [ID 197553 mail.info]
5C2EA445C: client=smtpgateway.sample.com[192.168.1.132]
Dec 11 10:21:07 solaris-host cleanup[29851]: [ID 197553 mail.info]
5C2EA445C: message-id=<Pine.LNX.4.58.00.14020otherhost.sample.com>
Dec 11 10:21:07 solaris-host qmgr[23898]: [ID 197553 mail.info]
5C2EA445C: from=<jacksolaris-host.sample.com>, size=5466, nrcpt=1
(queue active)
Dec 11 10:21:08 solaris-host local[27]: [ID 197553 mail.info]
5C2EA445C: to=<jacksolaris-host.sample.com>, relay=local, delay=0.71,
delays=0.12/0.19/0/0.4, dsn=4.2.0, status=SOFTBOUNCE (cannot update
mailbox /var/mail/jack for user jack. cannot open file: Permission
denied)
Dec 11 10:53:13 solaris-host qmgr[23898]: [ID 197553 mail.info]
5C2EA445C: from=<jacksolaris-host.sample.com>, size=5466, nrcpt=1
(queue active)
Dec 11 10:53:13 solaris-host local[1042]: [ID 197553 mail.info]
5C2EA445C: to=<jacksolaris-host.sample.com>, relay=local, delay=1926,
delays=1926/0.21/0/0.17, dsn=2.0.0, status=sent (delivered to mailbox)
Dec 11 10:53:13 solaris-host qmgr[23898]: [ID 197553 mail.info]
5C2EA445C: removed

This morning, I've taken the advice of switching to the dovecot LDA
"deliver" and that seems to be working. I'll be keeping an eye on it.

Thanks for the advice.

-John