ISPConfig 3 - Recieving Emails but not Sending sometimes huge delay.

Discussion in 'Server Operation' started by sptutusukanta, Oct 9, 2014.

  1. sptutusukanta

    sptutusukanta New Member

    Hello,
    I've installed ISPConfig 3 according to http://www.howtoforge.com/perfect-server-debian-wheezy-apache2-bind-dovecot-ispconfig-3. Everything is working great except sending emails.

    The mailq gets empty after a few secs of sending an email. But the emails not getting delivered. A few of them got delivered but after huge delay.

    Please Help.

    /var/log/mail.err => Empty.



    /var/log/mail.info

    Code:
    Oct  8 22:24:08 ns1 postfix/smtpd[1033]: 81AB0413DA: client=mail-lb0-f170.google.com[209.85.217.170]
    Oct  8 22:24:08 ns1 postfix/cleanup[995]: 81AB0413DA: message-id=<[email protected]m>
    Oct  8 22:24:08 ns1 postfix/qmgr[25484]: 81AB0413DA: from=<[email protected]>, size=1732, nrcpt=1 (queue active)
    Oct  8 22:24:08 ns1 postfix/smtpd[1033]: disconnect from mail-lb0-f170.google.com[209.85.217.170]
    Oct  8 22:24:18 ns1 postfix/smtpd[1037]: connect from localhost[127.0.0.1]
    Oct  8 22:24:18 ns1 postfix/smtpd[1037]: 45CCE41436: client=localhost[127.0.0.1]
    Oct  8 22:24:18 ns1 postfix/cleanup[995]: 45CCE41436: message-id=<[email protected]m>
    Oct  8 22:24:18 ns1 postfix/qmgr[25484]: 45CCE41436: from=<[email protected]>, size=2198, nrcpt=1 (queue active)
    Oct  8 22:24:18 ns1 postfix/smtpd[1037]: disconnect from localhost[127.0.0.1]
    Oct  8 22:24:18 ns1 amavis[26553]: (26553-10) Passed CLEAN {RelayedInbound}, [209.85.217.170]:39667 [209.85.217.170] <[email protected]> -> <[email protected]>, Queue-ID: 81AB0413DA, Message-ID: <[email protected]m>, mail_id: 7xumXIrtPNsu, Hits: 1.015, size: 1732, queued_as: 45CCE41436, 9556 ms
    Oct  8 22:24:18 ns1 postfix/smtp[996]: 81AB0413DA: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:10024, delay=9.9, delays=0.35/0/0.01/9.6, dsn=2.0.0, status=sent (250 2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as 45CCE41436)
    Oct  8 22:24:18 ns1 postfix/qmgr[25484]: 81AB0413DA: removed
    Oct  8 22:24:18 ns1 dovecot: auth-worker(1040): mysql(localhost): Connected to database dbispconfig
    Oct  8 22:24:18 ns1 dovecot: lda([email protected]): sieve: msgid=<[email protected]m>: stored mail into mailbox 'INBOX'
    Oct  8 22:24:18 ns1 postfix/pipe[1038]: 45CCE41436: to=<[email protected]>, relay=dovecot, delay=0.13, delays=0.01/0.03/0/0.1, dsn=2.0.0, status=sent (delivered via dovecot service)
    Oct  8 22:24:18 ns1 postfix/qmgr[25484]: 45CCE41436: removed
    Oct  8 22:25:01 ns1 postfix/smtpd[1033]: connect from localhost[127.0.0.1]
    Oct  8 22:25:01 ns1 postfix/smtpd[1033]: lost connection after CONNECT from localhost[127.0.0.1]
    Oct  8 22:25:01 ns1 postfix/smtpd[1033]: disconnect from localhost[127.0.0.1]
    Oct  8 22:25:02 ns1 dovecot: pop3-login: Disconnected (no auth attempts in 0 secs): user=<>, rip=127.0.0.1, lip=127.0.0.1, secured, session=<4WRzyfAEqQB/AAAB>
    Oct  8 22:25:02 ns1 dovecot: imap-login: Disconnected (no auth attempts in 0 secs): user=<>, rip=127.0.0.1, lip=127.0.0.1, secured, session=<sGNzyfAE0wB/AAAB>
    Oct  8 22:28:22 ns1 postfix/anvil[989]: statistics: max connection rate 1/60s for (smtp:209.85.217.170) at Oct  8 22:22:19
    Oct  8 22:28:22 ns1 postfix/anvil[989]: statistics: max connection count 1 for (smtp:209.85.217.170) at Oct  8 22:22:19
    Oct  8 22:28:22 ns1 postfix/anvil[989]: statistics: max message rate 1/60s for (smtp:209.85.217.170) at Oct  8 22:22:19
    Oct  8 22:28:22 ns1 postfix/anvil[989]: statistics: max cache size 1 at Oct  8 22:22:19
    Oct  8 22:30:01 ns1 dovecot: imap-login: Disconnected (disconnected before greeting, waited 0 secs): user=<>, rip=127.0.0.1, lip=127.0.0.1, secured, session=<gDRR2/AE2QB/AAAB>
    Oct  8 22:30:01 ns1 dovecot: pop3-login: Disconnected (no auth attempts in 0 secs): user=<>, rip=127.0.0.1, lip=127.0.0.1, secured, session=<QgFS2/AErwB/AAAB>
    Oct  8 22:30:01 ns1 postfix/smtpd[1202]: connect from localhost[127.0.0.1]
    Oct  8 22:30:01 ns1 postfix/smtpd[1202]: lost connection after CONNECT from localhost[127.0.0.1]
    Oct  8 22:30:01 ns1 postfix/smtpd[1202]: disconnect from localhost[127.0.0.1]
    


    /var/log/mail.log

    Code:
    Oct  8 22:20:02 ns1 postfix/smtpd[755]: disconnect from localhost[127.0.0.1]
    Oct  8 22:21:53 ns1 dovecot: auth-worker(968): mysql(localhost): Connected to database dbispconfig
    Oct  8 22:21:55 ns1 dovecot: pop3-login: Disconnected (auth failed, 1 attempts in 2 secs): user=<[email protected]>, method=PLAIN, rip=209.85.216.20, lip=104.131.70.90, session=<dK40vvAEJADRVdgU>
    Oct  8 22:22:19 ns1 postfix/smtpd[987]: connect from mail-lb0-f170.google.com[209.85.217.170]
    Oct  8 22:22:19 ns1 postfix/smtpd[987]: E2025413D9: client=mail-lb0-f170.google.com[209.85.217.170]
    Oct  8 22:22:20 ns1 postfix/cleanup[995]: E2025413D9: message-id=<[email protected]m>
    Oct  8 22:22:20 ns1 postfix/qmgr[25484]: E2025413D9: from=<[email protected]>, size=1761, nrcpt=1 (queue active)
    Oct  8 22:22:20 ns1 postfix/smtpd[987]: disconnect from mail-lb0-f170.google.com[209.85.217.170]
    Oct  8 22:22:29 ns1 postfix/smtpd[999]: connect from localhost[127.0.0.1]
    Oct  8 22:22:29 ns1 postfix/smtpd[999]: BF9C3413DA: client=localhost[127.0.0.1]
    Oct  8 22:22:29 ns1 postfix/cleanup[995]: BF9C3413DA: message-id=<[email protected]m>
    Oct  8 22:22:29 ns1 postfix/qmgr[25484]: BF9C3413DA: from=<[email protected]>, size=2237, nrcpt=1 (queue active)
    Oct  8 22:22:29 ns1 postfix/smtpd[999]: disconnect from localhost[127.0.0.1]
    Oct  8 22:22:29 ns1 amavis[26552]: (26552-10) Passed CLEAN {RelayedInbound}, [209.85.217.170]:58167 [209.85.217.170] <[email protected]> -> <[email protected]>, Queue-ID: E2025413D9, Message-ID: <[email protected]m>, mail_id: fUiHKZw0ByL4, Hits: 1.005, size: 1761, queued_as: BF9C3413DA, 9648 ms
    Oct  8 22:22:29 ns1 postfix/smtp[996]: E2025413D9: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:10024, delay=10, delays=0.37/0.02/0.01/9.6, dsn=2.0.0, status=sent (250 2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as BF9C3413DA)
    Oct  8 22:22:29 ns1 postfix/qmgr[25484]: E2025413D9: removed
    Oct  8 22:22:29 ns1 dovecot: lda([email protected]): sieve: msgid=<[email protected]m>: stored mail into mailbox 'INBOX'
    Oct  8 22:22:29 ns1 postfix/pipe[1000]: BF9C3413DA: to=<[email protected]>, orig_to=<[email protected]>, relay=dovecot, delay=0.11, delays=0.02/0.03/0/0.06, dsn=2.0.0, status=sent (delivered via dovecot service)
    Oct  8 22:22:29 ns1 postfix/qmgr[25484]: BF9C3413DA: removed
    Oct  8 22:24:07 ns1 postfix/smtpd[1033]: connect from mail-lb0-f170.google.com[209.85.217.170]
    Oct  8 22:24:08 ns1 postfix/smtpd[1033]: 81AB0413DA: client=mail-lb0-f170.google.com[209.85.217.170]
    Oct  8 22:24:08 ns1 postfix/cleanup[995]: 81AB0413DA: message-id=<[email protected]m>
    Oct  8 22:24:08 ns1 postfix/qmgr[25484]: 81AB0413DA: from=<[email protected]>, size=1732, nrcpt=1 (queue active)
    Oct  8 22:24:08 ns1 postfix/smtpd[1033]: disconnect from mail-lb0-f170.google.com[209.85.217.170]
    Oct  8 22:24:18 ns1 postfix/smtpd[1037]: connect from localhost[127.0.0.1]
    Oct  8 22:24:18 ns1 postfix/smtpd[1037]: 45CCE41436: client=localhost[127.0.0.1]
    Oct  8 22:24:18 ns1 postfix/cleanup[995]: 45CCE41436: message-id=<[email protected]m>
    Oct  8 22:24:18 ns1 postfix/qmgr[25484]: 45CCE41436: from=<[email protected]>, size=2198, nrcpt=1 (queue active)
    Oct  8 22:24:18 ns1 postfix/smtpd[1037]: disconnect from localhost[127.0.0.1]
    Oct  8 22:24:18 ns1 amavis[26553]: (26553-10) Passed CLEAN {RelayedInbound}, [209.85.217.170]:39667 [209.85.217.170] <[email protected]> -> <[email protected]>, Queue-ID: 81AB0413DA, Message-ID: <[email protected]m>, mail_id: 7xumXIrtPNsu, Hits: 1.015, size: 1732, queued_as: 45CCE41436, 9556 ms
    Oct  8 22:24:18 ns1 postfix/smtp[996]: 81AB0413DA: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:10024, delay=9.9, delays=0.35/0/0.01/9.6, dsn=2.0.0, status=sent (250 2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as 45CCE41436)
    Oct  8 22:24:18 ns1 postfix/qmgr[25484]: 81AB0413DA: removed
    Oct  8 22:24:18 ns1 dovecot: auth-worker(1040): mysql(localhost): Connected to database dbispconfig
    Oct  8 22:24:18 ns1 dovecot: lda([email protected]): sieve: msgid=<[email protected]m>: stored mail into mailbox 'INBOX'
    Oct  8 22:24:18 ns1 postfix/pipe[1038]: 45CCE41436: to=<[email protected]>, relay=dovecot, delay=0.13, delays=0.01/0.03/0/0.1, dsn=2.0.0, status=sent (delivered via dovecot service)
    Oct  8 22:24:18 ns1 postfix/qmgr[25484]: 45CCE41436: removed
    Oct  8 22:25:01 ns1 postfix/smtpd[1033]: connect from localhost[127.0.0.1]
    Oct  8 22:25:01 ns1 postfix/smtpd[1033]: lost connection after CONNECT from localhost[127.0.0.1]
    Oct  8 22:25:01 ns1 postfix/smtpd[1033]: disconnect from localhost[127.0.0.1]
    Oct  8 22:25:02 ns1 dovecot: pop3-login: Disconnected (no auth attempts in 0 secs): user=<>, rip=127.0.0.1, lip=127.0.0.1, secured, session=<4WRzyfAEqQB/AAAB>
    Oct  8 22:25:02 ns1 dovecot: imap-login: Disconnected (no auth attempts in 0 secs): user=<>, rip=127.0.0.1, lip=127.0.0.1, secured, session=<sGNzyfAE0wB/AAAB>
    Oct  8 22:28:22 ns1 postfix/anvil[989]: statistics: max connection rate 1/60s for (smtp:209.85.217.170) at Oct  8 22:22:19
    Oct  8 22:28:22 ns1 postfix/anvil[989]: statistics: max connection count 1 for (smtp:209.85.217.170) at Oct  8 22:22:19
    Oct  8 22:28:22 ns1 postfix/anvil[989]: statistics: max message rate 1/60s for (smtp:209.85.217.170) at Oct  8 22:22:19
    Oct  8 22:28:22 ns1 postfix/anvil[989]: statistics: max cache size 1 at Oct  8 22:22:19
    Oct  8 22:30:01 ns1 dovecot: imap-login: Disconnected (disconnected before greeting, waited 0 secs): user=<>, rip=127.0.0.1, lip=127.0.0.1, secured, session=<gDRR2/AE2QB/AAAB>
    Oct  8 22:30:01 ns1 dovecot: pop3-login: Disconnected (no auth attempts in 0 secs): user=<>, rip=127.0.0.1, lip=127.0.0.1, secured, session=<QgFS2/AErwB/AAAB>
    Oct  8 22:30:01 ns1 postfix/smtpd[1202]: connect from localhost[127.0.0.1]
    Oct  8 22:30:01 ns1 postfix/smtpd[1202]: lost connection after CONNECT from localhost[127.0.0.1]
    Oct  8 22:30:01 ns1 postfix/smtpd[1202]: disconnect from localhost[127.0.0.1]
    

    /var/log/mail.warn

    Code:
    Oct  8 19:45:57 ns1 dovecot: master: Warning: Killed with signal 15 (by pid=17760 uid=0 code=kill)
    Oct  8 19:45:58 ns1 dovecot: master: Warning: Killed with signal 15 (by pid=17921 uid=0 code=kill)
    Oct  8 19:45:58 ns1 dovecot: master: Warning: Killed with signal 15 (by pid=18082 uid=0 code=kill)
    Oct  8 20:22:32 ns1 dovecot: master: Warning: Killed with signal 15 (by pid=26560 uid=0 code=kill)
     
  2. srijan

    srijan New Member HowtoForge Supporter

    Check your mail que

    and paste your /etc/postfix/main.cf
     

Share This Page