I have finished to install Postfix following this howto "Virtual Users And Domains With Postfix, Courier And MySQL (Debian Etch)". To test the well running of the server I've decided to send an email o the first users. Below is the error message. Thanks more to help me solve this problem before using a mail agent like icedove. svrcrm:~# telnet 127.0.0.1 25 Trying 127.0.0.1... Connected to 127.0.0.1. Escape character is '^]'. 220 svrcrm.dcm-arobase.ci ESMTP Postfix (Debian/GNU) ehlo dcm-arobase.ci 250-svrcrm.dcm-arobase.ci 250-PIPELINING 250-SIZE 10240000 250-VRFY 250-ETRN 250-STARTTLS 250-AUTH LOGIN PLAIN 250-AUTH=LOGIN PLAIN 250-ENHANCEDSTATUSCODES 250-8BITMIME 250 DSN mail from: <[email protected]> 250 2.1.0 Ok rcpt to: <[email protected]> 250 2.1.5 Ok data 354 End data with <CR><LF>.<CR><LF> Voici le premier mail de test. . 250 2.0.0 Ok: queued as BC83643C1D quit 221 2.0.0 Bye Connection closed by foreign host. svrcrm:~# tail -f /var/log/mail.log Jul 18 12:43:08 svrcrm imapd-ssl: chdir dcm-arobase.ci/crmadmins/: No such file or directory Jul 18 12:44:05 svrcrm postfix/smtpd[17966]: BC83643C1D: client=localhost.localdomain[127.0.0.1] Jul 18 12:44:08 svrcrm imapd-ssl: Connection, ip=[::ffff:192.168.2.1] Jul 18 12:44:08 svrcrm imapd-ssl: chdir dcm-arobase.ci/crmadmins/: No such file or directory Jul 18 12:44:25 svrcrm postfix/cleanup[17973]: BC83643C1D: message-id=<[email protected]> Jul 18 12:44:25 svrcrm postfix/qmgr[17437]: BC83643C1D: from=<[email protected]>, size=405, nrcpt=1 (queue active) Jul 18 12:44:26 svrcrm amavis[17542]: (17542-02) (!) ClamAV-clamd: Can't connect to UNIX socket /var/run/clamav/clamd.ctl: Aucun fichier ou r\303\251pertoire de ce type, retrying (2) Jul 18 12:44:32 svrcrm amavis[17542]: (17542-02) (!!) ClamAV-clamd av-scanner FAILED: Too many retries to talk to /var/run/clamav/clamd.ctl (Can't connect to UNIX socket /var/run/clamav/clamd.ctl: Aucun fichier ou r\303\251pertoire de ce type) at (eval 44) line 268. Jul 18 12:44:32 svrcrm amavis[17542]: (17542-02) (!!) WARN: all primary virus scanners failed, considering backups Jul 18 12:44:35 svrcrm postfix/smtpd[17966]: disconnect from localhost.localdomain[127.0.0.1]
Please check of ClamAV is running. Also, you must send an email to a new account before you fetch mails. Maildir is created automatically when the first email arrives.
Hello Falko, I have restart all the services participating in this installation. After that when I try to connect to the mail server, the MUA don't send any error message. But the log file indicate a lot of errors. Below a copy of the log svrcrm:~# tail -f /var/log/mail.log Jul 23 10:18:24 svrcrm imapd-ssl: chdir dcm-arobase.ci/crmadmins/: No such file or directory Jul 23 10:19:24 svrcrm imapd-ssl: Connection, ip=[::ffff:192.168.2.1] Jul 23 10:19:24 svrcrm imapd-ssl: chdir dcm-arobase.ci/crmadmins/: No such file or directory Jul 23 10:20:24 svrcrm imapd-ssl: Connection, ip=[::ffff:192.168.2.1] Jul 23 10:20:24 svrcrm imapd-ssl: chdir dcm-arobase.ci/crmadmins/: No such file or directory Jul 23 10:21:24 svrcrm imapd-ssl: Connection, ip=[::ffff:192.168.2.1] Jul 23 10:21:24 svrcrm imapd-ssl: chdir dcm-arobase.ci/crmadmins/: No such file or directory Jul 23 10:21:27 svrcrm postfix/pickup[2570]: 215D143C48: uid=0 from=<root> Jul 23 10:21:27 svrcrm postfix/cleanup[2981]: 215D143C48: message-id=<[email protected]> Jul 23 10:21:27 svrcrm postfix/qmgr[2569]: 215D143C48: from=<[email protected]>, size=376, nrcpt=2 (queue active) Jul 23 10:21:33 svrcrm postfix/smtpd[2989]: fatal: unexpected command-line argument: 192.168.2.0/24 Jul 23 10:21:34 svrcrm amavis[2690]: (02690-06) (!) FWD via SMTP: <[email protected]> -> <[email protected]>,<[email protected]>, 450 4.4.1 Can't connect to 127.0.0.1 port 10025, (Bad file descriptor) at (eval 42) line 145, <GEN26> line 181., MTA([127.0.0.1]:10025), id=02690-06 Jul 23 10:21:34 svrcrm amavis[2690]: (02690-06) Blocked TEMPFAIL, <[email protected]> -> <[email protected]>,<[email protected]>, Message-ID: <[email protected]>, mail_id: 1QWhMYNV3EVL, Hits: 2.349, 6976 ms Jul 23 10:21:34 svrcrm postfix/master[2554]: warning: process /usr/lib/postfix/smtpd pid 2989 exit status 1 Jul 23 10:21:34 svrcrm postfix/master[2554]: warning: /usr/lib/postfix/smtpd: bad command startup -- throttling Jul 23 10:21:34 svrcrm postfix/smtp[2984]: 215D143C48: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:10024, delay=7.1, delays=0.15/0.01/0/7, dsn=4.4.1, status=deferred (host 127.0.0.1[127.0.0.1] said: 450 4.4.1 Can't connect to 127.0.0.1 port 10025, (Bad file descriptor) at (eval 42) line 145, <GEN26> line 181., MTA([127.0.0.1]:10025), id=02690-06 (in reply to end of DATA command)) Jul 23 10:21:34 svrcrm postfix/smtp[2984]: 215D143C48: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:10024, delay=7.1, delays=0.15/0.01/0/7, dsn=4.4.1, status=deferred (host 127.0.0.1[127.0.0.1] said: 450 4.4.1 Can't connect to 127.0.0.1 port 10025, (Bad file descriptor) at (eval 42) line 145, <GEN26> line 181., MTA([127.0.0.1]:10025), id=02690-06 (in reply to end of DATA command)) Jul 23 10:22:20 svrcrm postfix/qmgr[2569]: 9F31943C2E: from=<[email protected]>, size=295, nrcpt=1 (queue active) Jul 23 10:22:20 svrcrm postfix/qmgr[2569]: 55A2643C2A: from=<[email protected]>, size=497, nrcpt=1 (queue active) Jul 23 10:22:24 svrcrm imapd-ssl: Connection, ip=[::ffff:192.168.2.1] Jul 23 10:22:24 svrcrm imapd-ssl: chdir dcm-arobase.ci/crmadmins/: No such file or directory Jul 23 10:22:34 svrcrm postfix/smtpd[3001]: fatal: unexpected command-line argument: 192.168.2.0/24 Jul 23 10:22:34 svrcrm postfix/smtpd[3003]: fatal: unexpected command-line argument: 192.168.2.0/24 Jul 23 10:22:35 svrcrm amavis[2690]: (02690-07) (!) FWD via SMTP: <[email protected]> -> <[email protected]>, 450 4.4.1 Can't connect to 127.0.0.1 port 10025, (Bad file descriptor) at (eval 42) line 145, <GEN26> line 221., MTA([127.0.0.1]:10025), id=02690-07 Jul 23 10:22:35 svrcrm amavis[2690]: (02690-07) Blocked TEMPFAIL, <[email protected]> -> <[email protected]>, Message-ID: <[email protected]>, mail_id: S1bbzVEKOfma, Hits: -0.001, 14966 ms Jul 23 10:22:35 svrcrm postfix/master[2554]: warning: process /usr/lib/postfix/smtpd pid 3001 exit status 1 Jul 23 10:22:35 svrcrm postfix/master[2554]: warning: /usr/lib/postfix/smtpd: bad command startup -- throttling Jul 23 10:22:35 svrcrm postfix/smtp[2993]: 55A2643C2A: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:10024, delay=296554, delays=296539/0.02/0/15, dsn=4.4.1, status=deferred (host 127.0.0.1[127.0.0.1] said: 450 4.4.1 Can't connect to 127.0.0.1 port 10025, (Bad file descriptor) at (eval 42) line 145, <GEN26> line 221., MTA([127.0.0.1]:10025), id=02690-07 (in reply to end of DATA command)) Jul 23 10:22:35 svrcrm postfix/master[2554]: warning: process /usr/lib/postfix/smtpd pid 3003 exit status 1 Jul 23 10:22:35 svrcrm amavis[2691]: (02691-05) (!) FWD via SMTP: <[email protected]> -> <[email protected]>, 450 4.4.1 Can't connect to 127.0.0.1 port 10025, (Bad file descriptor) at (eval 42) line 145, <GEN26> line 897., MTA([127.0.0.1]:10025), id=02691-05 Jul 23 10:22:35 svrcrm amavis[2691]: (02691-05) Blocked BAD-HEADER, <[email protected]> -> <[email protected]>, quarantine: badh-Da56Uka0e6wX, Message-ID: <[email protected]>, mail_id: Da56Uka0e6wX, Hits: 5.988, 15012 ms Jul 23 10:22:35 svrcrm postfix/smtp[2984]: 9F31943C2E: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:10024, delay=271354, delays=271339/0.02/0/15, dsn=4.4.1, status=deferred (host 127.0.0.1[127.0.0.1] said: 450 4.4.1 Can't connect to 127.0.0.1 port 10025, (Bad file descriptor) at (eval 42) line 145, <GEN26> line 897., MTA([127.0.0.1]:10025), id=02691-05 (in reply to end of DATA command)) Jul 23 10:23:24 svrcrm imapd-ssl: Connection, ip=[::ffff:192.168.2.1] Jul 23 10:23:24 svrcrm imapd-ssl: chdir dcm-arobase.ci/crmadmins/: No such file or directory Jul 23 10:24:24 svrcrm imapd-ssl: Connection, ip=[::ffff:192.168.2.1] Jul 23 10:24:24 svrcrm imapd-ssl: chdir dcm-arobase.ci/crmadmins/: No such file or directory Jul 23 10:25:24 svrcrm imapd-ssl: Connection, ip=[::ffff:192.168.2.1] Jul 23 10:25:24 svrcrm imapd-ssl: chdir dcm-arobase.ci/crmadmins/: No such file or directory Jul 23 10:26:24 svrcrm imapd-ssl: Connection, ip=[::ffff:192.168.2.1] Jul 23 10:26:24 svrcrm imapd-ssl: chdir dcm-arobase.ci/crmadmins/: No such file or directory Jul 23 10:27:20 svrcrm postfix/qmgr[2569]: 215D143C48: from=<[email protected]>, size=376, nrcpt=2 (queue active) Jul 23 10:27:24 svrcrm imapd-ssl: Connection, ip=[::ffff:192.168.2.1] Jul 23 10:27:24 svrcrm imapd-ssl: chdir dcm-arobase.ci/crmadmins/: No such file or directory Jul 23 10:27:26 svrcrm postfix/smtpd[3019]: fatal: unexpected command-line argument: 192.168.2.0/24 Jul 23 10:27:27 svrcrm amavis[2690]: (02690-08) (!) FWD via SMTP: <[email protected]> -> <[email protected]>,<[email protected]>, 450 4.4.1 Can't connect to 127.0.0.1 port 10025, (Bad file descriptor) at (eval 42) line 145, <GEN26> line 250., MTA([127.0.0.1]:10025), id=02690-08 Jul 23 10:27:27 svrcrm amavis[2690]: (02690-08) Blocked TEMPFAIL, <[email protected]> -> <[email protected]>,<[email protected]>, Message-ID: <[email protected]>, mail_id: K-CDqjG-9c0q, Hits: 2.346, 6827 ms Jul 23 10:27:27 svrcrm postfix/master[2554]: warning: process /usr/lib/postfix/smtpd pid 3019 exit status 1 Jul 23 10:27:27 svrcrm postfix/master[2554]: warning: /usr/lib/postfix/smtpd: bad command startup -- throttling Jul 23 10:27:27 svrcrm postfix/smtp[3012]: 215D143C48: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:10024, delay=360, delays=353/0.01/0/6.8, dsn=4.4.1, status=deferred (host 127.0.0.1[127.0.0.1] said: 450 4.4.1 Can't connect to 127.0.0.1 port 10025, (Bad file descriptor) at (eval 42) line 145, <GEN26> line 250., MTA([127.0.0.1]:10025), id=02690-08 (in reply to end of DATA command)) Jul 23 10:27:27 svrcrm postfix/smtp[3012]: 215D143C48: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:10024, delay=360, delays=353/0.01/0/6.8, dsn=4.4.1, status=deferred (host 127.0.0.1[127.0.0.1] said: 450 4.4.1 Can't connect to 127.0.0.1 port 10025, (Bad file descriptor) at (eval 42) line 145, <GEN26> line 250., MTA([127.0.0.1]:10025), id=02690-08 (in reply to end of DATA command)) Jul 23 10:28:24 svrcrm imapd-ssl: Connection, ip=[::ffff:192.168.2.1] Jul 23 10:28:24 svrcrm imapd-ssl: chdir dcm-arobase.ci/crmadmins/: No such file or directory Jul 23 10:29:24 svrcrm imapd-ssl: Connection, ip=[::ffff:192.168.2.1] Jul 23 10:29:24 svrcrm imapd-ssl: chdir dcm-arobase.ci/crmadmins/: No such file or directory Jul 23 10:30:24 svrcrm imapd-ssl: Connection, ip=[::ffff:192.168.2.1] Jul 23 10:30:24 svrcrm imapd-ssl: chdir dcm-arobase.ci/crmadmins/: No such file or directory Jul 23 10:31:24 svrcrm imapd-ssl: Connection, ip=[::ffff:192.168.2.1] Jul 23 10:31:24 svrcrm imapd-ssl: chdir dcm-arobase.ci/crmadmins/: No such file or directory svrcrm:~# Thanks for your help
Hello Falko, below is the output of netstat -tap svrcrm:~# netstat -tap Connexions Internet actives (serveurs et établies) Proto Recv-Q Send-Q Adresse locale Adresse distante Etat PID/Program name tcp 0 0 localhost.localdoma:677 *:* LISTEN 2621/famd tcp 0 0 localhost.localdo:10024 *:* LISTEN 2141/amavisd (maste tcp 0 0 localhost.localdo:10025 *:* LISTEN 2554/master tcp 0 0 localhost.localdo:mysql *:* LISTEN 2203/mysqld tcp 0 0 *:sunrpc *:* LISTEN 1772/portmap tcp 0 0 svrcrm.dcm-aroba:domain *:* LISTEN 2081/named tcp 0 0 localhost.locald:domain *:* LISTEN 2081/named tcp 0 0 *:smtp *:* LISTEN 2554/master tcp 0 0 localhost.localdoma:953 *:* LISTEN 2081/named tcp6 0 0 *:imaps *:* LISTEN 17799/couriertcpd tcp6 0 0 *op3s *:* LISTEN 11275/couriertcpd tcp6 0 0 *op3 *:* LISTEN 11227/couriertcpd tcp6 0 0 *:imap2 *:* LISTEN 17761/couriertcpd tcp6 0 0 *:www *:* LISTEN 18592/apache2 tcp6 0 0 *:domain *:* LISTEN 2081/named tcp6 0 0 *:ssh *:* LISTEN 2097/sshd tcp6 0 0 ip6-localhost:953 *:* LISTEN 2081/named tcp6 0 1584 svrcrm.dcm-arobase.:ssh ::ffff:192.168.2.1:4534 ESTABLISHED18681/sshd: crmadmi svrcrm:~# Thanks more
Hello Falko, After rebooting the server, this is below the contain of mail.log. Thanks more for your help. svrcrm:~# tail -f /var/log/mail.log Jul 25 15:27:38 svrcrm postfix/master[2554]: warning: /usr/lib/postfix/smtpd: bad command startup -- throttling Jul 25 15:27:38 svrcrm amavis[24127]: (24127-01) Blocked TEMPFAIL, <[email protected]> -> <[email protected]>, Message-ID: <[email protected]>, mail_id: xM1k-AikxknM, Hits: 2.424, 8121 ms Jul 25 15:27:38 svrcrm postfix/smtp[24148]: DBA3943C3C: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:10024, delay=290425, delays=290417/0.02/0.01/8.1, dsn=4.4.1, status=deferred (host 127.0.0.1[127.0.0.1] said: 450 4.4.1 Can't connect to 127.0.0.1 port 10025, (Bad file descriptor) at (eval 42) line 145, <GEN26> line 778., MTA([127.0.0.1]:10025), id=24127-01 (in reply to end of DATA command)) Jul 25 15:32:30 svrcrm postfix/qmgr[2569]: 05C6043C34: from=<[email protected]>, size=295, nrcpt=1 (queue active) Jul 25 15:32:40 svrcrm postfix/smtpd[24170]: fatal: unexpected command-line argument: 192.168.2.0/24 Jul 25 15:32:41 svrcrm amavis[24006]: (24006-05) (!) FWD via SMTP: <[email protected]> -> <[email protected]>, 450 4.4.1 Can't connect to 127.0.0.1 port 10025, (Bad file descriptor) at (eval 42) line 145, <GEN26> line 157., MTA([127.0.0.1]:10025), id=24006-05 Jul 25 15:32:41 svrcrm postfix/master[2554]: warning: process /usr/lib/postfix/smtpd pid 24170 exit status 1 Jul 25 15:32:41 svrcrm postfix/master[2554]: warning: /usr/lib/postfix/smtpd: bad command startup -- throttling Jul 25 15:32:41 svrcrm amavis[24006]: (24006-05) Blocked BAD-HEADER, <[email protected]> -> <[email protected]>, quarantine: badh-iAmMbKtkeD5T, Message-ID: <[email protected]>, mail_id: iAmMbKtkeD5T, Hits: 6.066, 10232 ms Jul 25 15:32:41 svrcrm postfix/smtp[24165]: 05C6043C34: to=<[email protected]>, relay=127.0.0.1[127.0.0.1]:10024, delay=376359, delays=376349/0.13/0/10, dsn=4.4.1, status=deferred (host 127.0.0.1[127.0.0.1] said: 450 4.4.1 Can't connect to 127.0.0.1 port 10025, (Bad file descriptor) at (eval 42) line 145, <GEN26> line 157., MTA([127.0.0.1]:10025), id=24006-05 (in reply to end of DATA command))
