1

Topic: status=deferred (temporary failure)

I'm somewhat stumped as to the problem I'm having. 

Mail seems to arrive on my server without problem.
I watch it pass though the greylisting, and then get accepted

(mail-ew0-f225.google.com), from=user@gmail.com, to=user@myhost.us, size=0
Sep 24 23:53:49 mail postfix/smtpd[2353]: NOQUEUE: reject: RCPT from mail-ew0-f225.google.com[209.85.219.225]: 450 4.7.1 <user@myhost.us>: Recipient address rejected: Policy Rejection- Please try later.; from=<user@gmail.com> to=<user@myhost.us> proto=ESMTP helo=<mail-ew0-f225.google.com>
Sep 24 23:53:49 mail postfix/smtpd[2353]: disconnect from mail-ew0-f225.google.com[209.85.219.225]

Sep 25 00:01:46 mail postfix-policyd: rcpt=8, greylist=update, host=209.85.219.225 (mail-ew0-f225.google.com), from=user@gmail.com, to=user@myhost.us, size=0
Sep 25 00:01:46 mail postfix-policyd: rcpt=8, throttle_rcpt=clear(a), host=209.85.219.225, from=user@gmail.com, to=user@myhost.us, count=0/64(3), threshold=0%
Sep 25 00:01:46 mail postfix/smtpd[2398]: 63BFF2E2DC: client=mail-ew0-f225.google.com[209.85.219.225]
Sep 25 00:01:46 mail postfix/cleanup[2402]: 63BFF2E2DC: message-id=<05533294-742E-41C3-8E20-796EEF72689C@gmail.com>
Sep 25 00:01:46 mail postfix/qmgr[2182]: 63BFF2E2DC: from=<user@gmail.com>, size=2345, nrcpt=1 (queue active)

When I do a mailq, I see that the message is always there
50FE42E2EE     2981 Fri Sep 25 00:01:47  user@gmail.com
                                                           (temporary failure)
                                         user@myhost.us


I have no problems sending mail to the internet. 
Sep 24 23:53:07 mail postfix/smtpd[2351]: 73B942E2EE: client=localhost[127.0.0.1]
Sep 24 23:53:07 mail postfix/cleanup[2342]: 73B942E2EE: message-id=<F1778DE9-E12B-4659-B196-73DCDA1AC066@myhost.us>
Sep 24 23:53:07 mail postfix/qmgr[2182]: 73B942E2EE: from=<user@myhost.us>, size=1484, nrcpt=1 (queue active)
Sep 24 23:53:07 mail postfix/smtpd[2351]: disconnect from localhost[127.0.0.1]
Sep 24 23:53:07 mail amavis[1438]: (01438-02) Passed CLEAN, MYNETS LOCAL [192.168.111.254] [192.168.111.254] <user@myhost.us> -> <user@gmail.com>, Message-ID: <F1778DE9-E12B-4659-B196-73DCDA1AC066@myhost.us>, mail_id: cjA2+Jt1Pru4, Hits: -8.55, size: 576, queued_as: 73B942E2EE, 544 ms
Sep 24 23:53:07 mail postfix/smtp[2348]: DC0BC2E2DC: to=<user@gmail.com>, relay=127.0.0.1[127.0.0.1]:10024, delay=0.72, delays=0.13/0.03/0.01/0.55, dsn=2.0.0, status=sent (250 2.0.0 Ok, id=01438-02, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 73B942E2EE)
Sep 24 23:53:07 mail postfix/qmgr[2182]: DC0BC2E2DC: removed
Sep 24 23:53:07 mail postfix/smtp[2352]: 73B942E2EE: to=<user@gmail.com>, relay=smtp.free.fr[212.27.48.4]:25, delay=0.22, delays=0.02/0.05/0.08/0.07, dsn=2.0.0, status=sent (250 2.0.0 Ok: queued as AC1DF4C80DB)
Sep 24 23:53:07 mail postfix/qmgr[2182]: 73B942E2EE: removed

I've tried tweaking main.cf, using postsuper to flush everything, but the messages always seem to get stuck. The communication between dovecot and sql seems to be ok for sending, but for whatever reason for incoming messages there has to be something missing.

any ideas? thanks

----

Spider Email Archiver: On-Premises, lightweight email archiving software developed by iRedMail team. Supports Amazon S3 compatible storage and custom branding.

2

Re: status=deferred (temporary failure)

You didn't post the log which mention why it was temporary failule.

Please re-inject it into queue and monitor maillog to check why deliver failed:

# postqueue -f 50FE42E2EE

Here 50FE42E2EE is mail queue id you post above.

3

Re: status=deferred (temporary failure)

'll give you a two new ones as I've purged everything

Sep 26 08:39:01 mail postfix/smtpd[3373]: connect from mail-fx0-f212.google.com[209.85.220.212]
Sep 26 08:39:01 mail postfix/smtpd[3373]: 7CA342E2DC: client=mail-fx0-f212.google.com[209.85.220.212]
Sep 26 08:39:01 mail postfix/cleanup[3390]: 7CA342E2DC: message-id=<446b46720909252332y210888a7r587fbb18792600bc@mail.gmail.com>
Sep 26 08:39:01 mail postfix/qmgr[3345]: 7CA342E2DC: from=<myuser@gmail.com>, size=1637, nrcpt=1 (queue active)
Sep 26 08:39:03 mail postfix/smtpd[3399]: connect from mail.mydomain.us[127.0.0.1]
Sep 26 08:39:03 mail postfix/smtpd[3399]: 51DD02E2EB: client=mail.mydomain.us[127.0.0.1]
Sep 26 08:39:03 mail postfix/cleanup[3390]: 51DD02E2EB: message-id=<446b46720909252332y210888a7r587fbb18792600bc@mail.gmail.com>
Sep 26 08:39:03 mail postfix/qmgr[3345]: 51DD02E2EB: from=<myuser@gmail.com>, size=2281, nrcpt=1 (queue active)
Sep 26 08:39:03 mail amavis[1400]: (01400-01) Passed CLEAN, LOCAL [209.85.220.212] [209.85.220.212] <myuser@gmail.com> -> <user@mydomain.us>, Message-ID: <446b46720909252332y210888a7r587fbb18792600bc@mail.gmail.com>, mail_id: sv11KBuTedSB, Hits: -109.201, size: 1637, queued_as: 51DD02E2EB, dkim_id=@gmail.com,myuser@gmail.com, 1809 ms
Sep 26 08:39:03 mail postfix/pipe[3400]: 51DD02E2EB: to=<user@mydomain.us>, relay=dovecot, delay=0.15, delays=0.03/0.06/0/0.06, dsn=4.3.0, status=deferred (temporary failure)
Sep 26 08:39:03 mail postfix/smtp[3396]: 7CA342E2DC: to=<user@mydomain.us>, relay=127.0.0.1[127.0.0.1]:10024, delay=2.3, delays=0.4/0.02/0.03/1.8, dsn=2.0.0, status=sent (250 2.0.0 Ok, id=01400-01, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as 51DD02E2EB)
Sep 26 08:39:03 mail postfix/qmgr[3345]: 7CA342E2DC: removed
Sep 26 08:39:06 mail postfix/smtpd[3404]: connect from mail-bw0-f212.google.com[209.85.218.212]
Sep 26 08:39:06 mail postfix/smtpd[3404]: E21AB2E2DC: client=mail-bw0-f212.google.com[209.85.218.212]
Sep 26 08:39:07 mail postfix/cleanup[3390]: E21AB2E2DC: message-id=<446b46720909252338l2ee0b602g1424013f96591ee2@mail.gmail.com>
Sep 26 08:39:07 mail postfix/qmgr[3345]: E21AB2E2DC: from=<myuser@gmail.com>, size=1629, nrcpt=1 (queue active)
Sep 26 08:39:07 mail postfix/smtpd[3408]: connect from mail.mydomain.us[127.0.0.1]
Sep 26 08:39:07 mail postfix/smtpd[3408]: CBD942E2EC: client=mail.mydomain.us[127.0.0.1]
Sep 26 08:39:07 mail postfix/cleanup[3390]: CBD942E2EC: message-id=<446b46720909252338l2ee0b602g1424013f96591ee2@mail.gmail.com>
Sep 26 08:39:07 mail postfix/qmgr[3345]: CBD942E2EC: from=<myuser@gmail.com>, size=2273, nrcpt=1 (queue active)
Sep 26 08:39:07 mail postfix/smtpd[3408]: disconnect from mail.mydomain.us[127.0.0.1]
Sep 26 08:39:07 mail postfix/pipe[3400]: CBD942E2EC: to=<user@mydomain.us>, relay=dovecot, delay=0.03, delays=0.01/0/0/0.02, dsn=4.3.0, status=deferred (temporary failure)
Sep 26 08:39:07 mail amavis[1397]: (01397-02) Passed CLEAN, LOCAL [209.85.218.212] [209.85.218.212] <myuser@gmail.com> -> <user@mydomain.us>, Message-ID: <446b46720909252338l2ee0b602g1424013f96591ee2@mail.gmail.com>, mail_id: BLplMYZ0I5He, Hits: -109.401, size: 1629, queued_as: CBD942E2EC, dkim_id=@gmail.com,myuser@gmail.com, 821 ms
Sep 26 08:39:07 mail postfix/smtp[3396]: E21AB2E2DC: to=<user@mydomain.us>, relay=127.0.0.1[127.0.0.1]:10024, delay=1, delays=0.19/0/0.03/0.82, dsn=2.0.0, status=sent (250 2.0.0 Ok, id=01397-02, from MTA([127.0.0.1]:10025): 250 2.0.0 Ok: queued as CBD942E2EC)
Sep 26 08:39:07 mail postfix/qmgr[3345]: E21AB2E2DC: removed
Sep 26 08:39:31 mail postfix/smtpd[3373]: disconnect from mail-fx0-f212.google.com[209.85.220.212]
Sep 26 08:39:37 mail postfix/smtpd[3404]: disconnect from mail-bw0-f212.google.com[209.85.218.212]


However at in dovecot.log there is nothing
Sep 26 08:31:23 dovecot: Info: Dovecot v1.2.4 starting up (core dumps disabled)
Sep 26 08:31:23 auth-worker(default): Info: mysql: Connected to localhost (vmail)

mailq gives me this

ail:~# mailq     
-Queue ID- --Size-- ----Arrival Time---- -Sender/Recipient-------
CBD942E2EC     2273 Sat Sep 26 08:39:07  myuser@gmail.com
                                                           (temporary failure)
                                         user@mydomain.us

51DD02E2EB     2281 Sat Sep 26 08:39:03  myuser@gmail.com
                                                           (temporary failure)
                                         user@mydomain.us


postqueue -i 51DD02E2EB
Sep 26 08:47:02 mail postfix/qmgr[3345]: 51DD02E2EB: from=<myuser@gmail.com>, size=2281, nrcpt=1 (queue active)
Sep 26 08:47:02 mail postfix/pipe[3433]: 51DD02E2EB: to=<user@mydomain.us>, relay=dovecot, delay=479, delays=479/0.03/0/0.01, dsn=4.3.0, status=deferred (temporary failure)
postqueue -i CBD942E2EC
Sep 26 08:54:11 mail postfix/qmgr[3345]: CBD942E2EC: from=<myuser@gmail.com>, size=2273, nrcpt=1 (queue active)
Sep 26 08:54:11 mail postfix/pipe[3476]: CBD942E2EC: to=<user@mydomain.us>, relay=dovecot, delay=904, delays=904/0.05/0/0.02, dsn=4.3.0, status=deferred (temporary failure)


It must be something where postfix isn't seeing the virtual domains.

4

Re: status=deferred (temporary failure)

It seems there's some error while delivering mail. Could you please paste log in /var/log/sieve.log?

If no more detail in /var/log/sieve.log, please set 'mail_debug = yes' in dovecot configure file (/etc/dovecot.conf on RHEL/CentOS or /etc/dovecot/dovecot.conf on Debian/Ubuntu), and then run postqueue -f QUEUE_ID and monitor /var/log/sieve.log & /var/log/dovecot.log.

5

Re: status=deferred (temporary failure)

Users can sent email successfully but mails not shown under their respective mailboxs:
below is the error:
[root@mail ~]# tail -f /var/log/sieve.log
deliver(rashid@justlinux4u.com): Jul 28 12:35:18 Fatal: setgid(501(vboxusers)) failed with euid=501(vmail), gid=502(vmail), egid=12(mail): Operation not permitted
deliver(atif@justlinux4u.com): Jul 28 12:40:18 Fatal: setgid(501(vboxusers)) failed with euid=501(vmail), gid=502(vmail), egid=12(mail): Operation not permitted
deliver(rashid@justlinux4u.com): Jul 28 12:40:18 Fatal: setgid(501(vboxusers)) failed with euid=501(vmail), gid=502(vmail), egid=12(mail): Operation not permitted
deliver(rashid@justlinux4u.com): Jul 28 12:50:16 Fatal: setgid(501(vboxusers)) failed with euid=501(vmail), gid=502(vmail), egid=12(mail): Operation not permitted
deliver(rashid@justlinux4u.com): Jul 28 12:55:18 Fatal: setgid(501(vboxusers)) failed with euid=501(vmail), gid=502(vmail), egid=12(mail): Operation not permitted
deliver(rashid@justlinux4u.com): Jul 28 12:55:18 Fatal: setgid(501(vboxusers)) failed with euid=501(vmail), gid=502(vmail), egid=12(mail): Operation not permitted
deliver(atif@justlinux4u.com): Jul 28 13:00:18 Fatal: setgid(501(vboxusers)) failed with euid=501(vmail), gid=502(vmail), egid=12(mail): Operation not permitted
deliver(rashid@justlinux4u.com): Jul 28 13:00:18 Fatal: setgid(501(vboxusers)) failed with euid=501(vmail), gid=502(vmail), egid=12(mail): Operation not permitted
deliver(rashid@justlinux4u.com): Jul 28 13:05:18 Fatal: setgid(501(vboxusers)) failed with euid=501(vmail), gid=502(vmail), egid=12(mail): Operation not permitted
deliver(rashid@justlinux4u.com): Jul 28 13:25:18 Fatal: setgid(501(vboxusers)) failed with euid=501(vmail), gid=502(vmail), egid=12(mail): Operation not permitted


###########################################################

Kindly help me to sort out this issue.

Regars,

6

Re: status=deferred (temporary failure)

Please post your question in a seperate forum topic.