Email Getting Stuck/Held in Queue

Discussion in 'Installation/Configuration' started by BigBuddy, Feb 18, 2011.

  1. BigBuddy

    BigBuddy New Member

    I have ISPConfig 3 installed on an Apache Debian Lenny web server and am having issues sending emails via contact forms on my various websites. I do not run this server as an email server, although it does have that capability, so the only emails going out are ones that are being sent via my web forms (example: http://bigbuddymedia.com/contact ).

    When my server was set up by Till, he installed amavis to run a virus scan on emails before they are sent out and I'm pretty sure this is causing the problem. The problem is that I have emails (sometimes one, sometimes several) that get stuck in the mail queue and don't get sent.

    I check periodically to see if there are any emails in the queue and when I find some, I run through a list of commands to get them sent.

    Is there any way you can fix this issue for good, maybe by bypassing the virus scan altogether, or whatever you recommend so these emails are being sent properly. It's been very frustrating. Thanks.
     
  2. damir

    damir New Member

    Post you log files from mail.log or syslog.
     
  3. BigBuddy

    BigBuddy New Member

    Feb 18 11:45:03 buddy1 imapd: Connection, ip=[::ffff:127.0.0.1]
    Feb 18 11:45:03 buddy1 imapd: Disconnected, ip=[::ffff:127.0.0.1], time=0
    Feb 18 11:45:03 buddy1 pop3d: Disconnected, ip=[::ffff:127.0.0.1]
    Feb 18 11:45:03 buddy1 postfix/smtpd[20112]: connect from localhost[127.0.0.1]
    Feb 18 11:45:03 buddy1 postfix/smtpd[20112]: lost connection after CONNECT from localhost[127.0.0.1]
    Feb 18 11:45:03 buddy1 postfix/smtpd[20112]: disconnect from localhost[127.0.0.1]
    Feb 18 11:50:04 buddy1 pop3d: Connection, ip=[::ffff:127.0.0.1]
    Feb 18 11:50:04 buddy1 pop3d: Disconnected, ip=[::ffff:127.0.0.1]
    Feb 18 11:50:04 buddy1 imapd: Connection, ip=[::ffff:127.0.0.1]
    Feb 18 11:50:04 buddy1 imapd: Disconnected, ip=[::ffff:127.0.0.1], time=0
    Feb 18 11:50:05 buddy1 postfix/smtpd[20176]: connect from localhost[127.0.0.1]
    Feb 18 11:50:05 buddy1 postfix/smtpd[20176]: lost connection after CONNECT from localhost[127.0.0.1]
    Feb 18 11:50:05 buddy1 postfix/smtpd[20176]: disconnect from localhost[127.0.0.1]
    Feb 18 11:55:07 buddy1 pop3d: Connection, ip=[::ffff:127.0.0.1]
    Feb 18 11:55:07 buddy1 pop3d: Disconnected, ip=[::ffff:127.0.0.1]
    Feb 18 11:55:07 buddy1 imapd: Connection, ip=[::ffff:127.0.0.1]
    Feb 18 11:55:07 buddy1 imapd: Disconnected, ip=[::ffff:127.0.0.1], time=0
    Feb 18 11:55:09 buddy1 postfix/smtpd[20242]: connect from localhost[127.0.0.1]
    Feb 18 11:55:09 buddy1 postfix/smtpd[20242]: lost connection after CONNECT from localhost[127.0.0.1]
    Feb 18 11:55:09 buddy1 postfix/smtpd[20242]: disconnect from localhost[127.0.0.1]
    Feb 18 12:00:07 buddy1 imapd: Connection, ip=[::ffff:127.0.0.1]
    Feb 18 12:00:07 buddy1 imapd: Disconnected, ip=[::ffff:127.0.0.1], time=0
    Feb 18 12:00:07 buddy1 pop3d: Connection, ip=[::ffff:127.0.0.1]
    Feb 18 12:00:07 buddy1 pop3d: Disconnected, ip=[::ffff:127.0.0.1]
    Feb 18 12:00:08 buddy1 postfix/smtpd[20329]: connect from localhost[127.0.0.1]
    Feb 18 12:00:08 buddy1 postfix/smtpd[20329]: lost connection after CONNECT from localhost[127.0.0.1]
    Feb 18 12:00:08 buddy1 postfix/smtpd[20329]: disconnect from localhost[127.0.0.1]
    Feb 18 12:05:02 buddy1 pop3d: Connection, ip=[::ffff:127.0.0.1]
    Feb 18 12:05:02 buddy1 pop3d: Disconnected, ip=[::ffff:127.0.0.1]
    Feb 18 12:05:02 buddy1 imapd: Connection, ip=[::ffff:127.0.0.1]
    Feb 18 12:05:02 buddy1 imapd: Disconnected, ip=[::ffff:127.0.0.1], time=0
    Feb 18 12:05:02 buddy1 postfix/smtpd[20464]: connect from localhost[127.0.0.1]
    Feb 18 12:05:02 buddy1 postfix/smtpd[20464]: lost connection after CONNECT from localhost[127.0.0.1]
    Feb 18 12:05:02 buddy1 postfix/smtpd[20464]: disconnect from localhost[127.0.0.1]
    Feb 18 12:10:06 buddy1 pop3d: Connection, ip=[::ffff:127.0.0.1]
    Feb 18 12:10:06 buddy1 pop3d: Disconnected, ip=[::ffff:127.0.0.1]
    Feb 18 12:10:06 buddy1 imapd: Connection, ip=[::ffff:127.0.0.1]
    Feb 18 12:10:06 buddy1 imapd: Disconnected, ip=[::ffff:127.0.0.1], time=0
    Feb 18 12:10:07 buddy1 postfix/smtpd[20545]: connect from localhost[127.0.0.1]
    Feb 18 12:10:07 buddy1 postfix/smtpd[20545]: lost connection after CONNECT from localhost[127.0.0.1]
    Feb 18 12:10:07 buddy1 postfix/smtpd[20545]: disconnect from localhost[127.0.0.1]
    Feb 18 12:15:04 buddy1 pop3d: Connection, ip=[::ffff:127.0.0.1]
    Feb 18 12:15:04 buddy1 pop3d: Disconnected, ip=[::ffff:127.0.0.1]
    Feb 18 12:15:04 buddy1 imapd: Connection, ip=[::ffff:127.0.0.1]
    Feb 18 12:15:04 buddy1 imapd: Disconnected, ip=[::ffff:127.0.0.1], time=0
    Feb 18 12:15:06 buddy1 postfix/smtpd[20609]: connect from localhost[127.0.0.1]
    Feb 18 12:15:06 buddy1 postfix/smtpd[20609]: lost connection after CONNECT from localhost[127.0.0.1]
    Feb 18 12:15:06 buddy1 postfix/smtpd[20609]: disconnect from localhost[127.0.0.1]
    Feb 18 12:15:22 buddy1 postfix/smtpd[20609]: warning: 189.25.63.218: hostname 18925063218.user.veloxzone.com.br verification failed: Name or service not known
    Feb 18 12:15:22 buddy1 postfix/smtpd[20609]: connect from unknown[189.25.63.218]
    Feb 18 12:15:24 buddy1 postfix/smtpd[20609]: NOQUEUE: reject: RCPT from unknown[189.25.63.218]: 554 5.7.1 : Relay access denied; from= to= proto=ESMTP helo=<18925063218.user.veloxzone.com.br>
    Feb 18 12:15:24 buddy1 postfix/smtpd[20609]: lost connection after RCPT from unknown[189.25.63.218]
    Feb 18 12:15:24 buddy1 postfix/smtpd[20609]: disconnect from unknown[189.25.63.218]
    Feb 18 12:17:33 buddy1 postfix/smtpd[20641]: warning: 27.0.14.96: address not listed for hostname mx1496.superdata.vn
    Feb 18 12:17:33 buddy1 postfix/smtpd[20641]: connect from unknown[27.0.14.96]
    Feb 18 12:17:47 buddy1 postfix/smtpd[20641]: NOQUEUE: reject: RCPT from unknown[27.0.14.96]: 554 5.7.1 : Relay access denied; from= to= proto=ESMTP helo=
    Feb 18 12:17:48 buddy1 postfix/smtpd[20641]: disconnect from unknown[27.0.14.96]
    Feb 18 12:20:05 buddy1 imapd: Connection, ip=[::ffff:127.0.0.1]
    Feb 18 12:20:05 buddy1 imapd: Disconnected, ip=[::ffff:127.0.0.1], time=0
    Feb 18 12:20:05 buddy1 pop3d: Connection, ip=[::ffff:127.0.0.1]
    Feb 18 12:20:05 buddy1 pop3d: Disconnected, ip=[::ffff:127.0.0.1]
    Feb 18 12:20:06 buddy1 postfix/smtpd[20683]: connect from localhost[127.0.0.1]
    Feb 18 12:20:06 buddy1 postfix/smtpd[20683]: lost connection after CONNECT from localhost[127.0.0.1]
    Feb 18 12:20:06 buddy1 postfix/smtpd[20683]: disconnect from localhost[127.0.0.1]
    Feb 18 12:21:08 buddy1 postfix/anvil[20626]: statistics: max connection rate 1/60s for (smtp:189.25.63.218) at Feb 18 12:15:23
    Feb 18 12:21:08 buddy1 postfix/anvil[20626]: statistics: max connection count 1 for (smtp:189.25.63.218) at Feb 18 12:15:23
    Feb 18 12:21:08 buddy1 postfix/anvil[20626]: statistics: max cache size 1 at Feb 18 12:15:23
    Feb 18 12:25:03 buddy1 imapd: Connection, ip=[::ffff:127.0.0.1]
    Feb 18 12:25:03 buddy1 pop3d: Connection, ip=[::ffff:127.0.0.1]
    Feb 18 12:25:03 buddy1 pop3d: Disconnected, ip=[::ffff:127.0.0.1]
    Feb 18 12:25:03 buddy1 imapd: Disconnected, ip=[::ffff:127.0.0.1], time=0
    Feb 18 12:25:05 buddy1 postfix/smtpd[20747]: connect from localhost[127.0.0.1]
    Feb 18 12:25:05 buddy1 postfix/smtpd[20747]: lost connection after CONNECT from localhost[127.0.0.1]
    Feb 18 12:25:05 buddy1 postfix/smtpd[20747]: disconnect from localhost[127.0.0.1]
    Feb 18 12:30:03 buddy1 imapd: Connection, ip=[::ffff:127.0.0.1]
    Feb 18 12:30:03 buddy1 pop3d: Connection, ip=[::ffff:127.0.0.1]
    Feb 18 12:30:03 buddy1 imapd: Disconnected, ip=[::ffff:127.0.0.1], time=0
    Feb 18 12:30:03 buddy1 pop3d: Disconnected, ip=[::ffff:127.0.0.1]
    Feb 18 12:30:04 buddy1 postfix/smtpd[20813]: connect from localhost[127.0.0.1]
    Feb 18 12:30:04 buddy1 postfix/smtpd[20813]: lost connection after CONNECT from localhost[127.0.0.1]
    Feb 18 12:30:04 buddy1 postfix/smtpd[20813]: disconnect from localhost[127.0.0.1]
    Feb 18 12:30:17 buddy1 postfix/qmgr[16832]: 8845780291: from=, size=1734, nrcpt=1 (queue active)
    Feb 18 12:30:17 buddy1 postfix/qmgr[16832]: 192A380248: from=, size=822, nrcpt=1 (queue active)
    Feb 18 12:30:17 buddy1 postfix/qmgr[16832]: 7C20880284: from=, size=20599, nrcpt=1 (queue active)
    Feb 18 12:30:17 buddy1 postfix/smtp[20830]: connect to 127.0.0.1[127.0.0.1]:10024: Connection refused
    Feb 18 12:30:17 buddy1 postfix/smtp[20831]: connect to 127.0.0.1[127.0.0.1]:10024: Connection refused
    Feb 18 12:30:18 buddy1 postfix/smtp[20830]: 8845780291: to=, relay=none, delay=19841, delays=19841/0.16/0/0, dsn=4.4.1, status=deferred (connect to 127.0.0.1[127.0.0.1]:10024: Connection refused)
    Feb 18 12:30:18 buddy1 postfix/smtp[20831]: 192A380248: to=, relay=none, delay=48524, delays=48524/0.07/0/0, dsn=4.4.1, status=deferred (connect to 127.0.0.1[127.0.0.1]:10024: Connection refused)
    Feb 18 12:30:18 buddy1 postfix/smtp[20831]: connect to 127.0.0.1[127.0.0.1]:10024: Connection refused
    Feb 18 12:30:18 buddy1 postfix/smtp[20831]: 7C20880284: to=, orig_to=, relay=none, delay=27409, delays=27409/0.18/0.01/0, dsn=4.4.1, status=deferred (connect to 127.0.0.1[127.0.0.1]:10024: Connection refused)
    Feb 18 12:33:38 buddy1 postfix/scache[20834]: statistics: start interval Feb 18 12:30:18
    Feb 18 12:33:38 buddy1 postfix/scache[20834]: statistics: domain lookup hits=0 miss=1 success=0%
    Feb 18 12:33:38 buddy1 postfix/scache[20834]: statistics: address lookup hits=0 miss=1 success=0%
    Feb 18 12:35:03 buddy1 imapd: Connection, ip=[::ffff:127.0.0.1]
    Feb 18 12:35:03 buddy1 pop3d: Connection, ip=[::ffff:127.0.0.1]
    Feb 18 12:35:03 buddy1 imapd: Disconnected, ip=[::ffff:127.0.0.1], time=0
    Feb 18 12:35:03 buddy1 pop3d: Disconnected, ip=[::ffff:127.0.0.1]
    Feb 18 12:35:04 buddy1 postfix/smtpd[20883]: connect from localhost[127.0.0.1]
    Feb 18 12:35:04 buddy1 postfix/smtpd[20883]: lost connection after CONNECT from localhost[127.0.0.1]
    Feb 18 12:35:04 buddy1 postfix/smtpd[20883]: disconnect from localhost[127.0.0.1]
     
  4. falko

    falko Super Moderator Howtoforge Staff

    It seems as if amavisd isn't running. Please restart it.
     
  5. BigBuddy

    BigBuddy New Member

    Yes that seems to be the problem. So then I do restart it but the problem happens again and again. I guess the real question is why would amavis stop running randomly? Could it be a memory issue? I have a VPS with 10GB storage and 256MB RAM.

    Sometimes it runs without a problem for several days, and other times it seems to stop running several times a day for several days. It is really random.

    Is there any way to just bypass amavis and have the mail sent without a scan?
     
  6. falko

    falko Super Moderator Howtoforge Staff

    256 MB is not that much . I'd recommend at least 512 MB RAM.
     

Share This Page