HowtoForge Forums | HowtoForge - Linux Howtos and Tutorials

HowtoForge Forums | HowtoForge - Linux Howtos and Tutorials (http://www.howtoforge.com/forums/index.php)
-   Installation/Configuration (http://www.howtoforge.com/forums/forumdisplay.php?f=4)
-   -   Unable to send and receive mail in command line mode (http://www.howtoforge.com/forums/showthread.php?t=14128)

kdclaver 18th July 2007 14:53

Unable to send and receive mail in command line mode
 
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: <crmadmins@dcm-arobase.ci>
250 2.1.0 Ok
rcpt to: <crmadmins@dcm-arobase.ci>
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=<20070718124405.BC83643C1D@svrcrm.dcm-arobase.ci>
Jul 18 12:44:25 svrcrm postfix/qmgr[17437]: BC83643C1D: from=<crmadmins@dcm-arobase.ci>, 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]

falko 19th July 2007 14:40

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.

kdclaver 23rd July 2007 13:33

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=<20070723102127.215D143C48@svrcrm.dcm-arobase.ci>
Jul 23 10:21:27 svrcrm postfix/qmgr[2569]: 215D143C48: from=<root@svrcrm.dcm-arobase.ci>, 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: <root@svrcrm.dcm-arobase.ci> -> <crmadmins@dcm-arobase.ci>,<kdclaver@gmail.com>, 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, <root@svrcrm.dcm-arobase.ci> -> <crmadmins@dcm-arobase.ci>,<kdclaver@gmail.com>, Message-ID: <20070723102127.215D143C48@svrcrm.dcm-arobase.ci>, 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=<crmadmins@dcm-arobase.ci>, 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=<kdclaver@gmail.com>, 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=<root@svrcrm.dcm-arobase.ci>, size=295, nrcpt=1 (queue active)
Jul 23 10:22:20 svrcrm postfix/qmgr[2569]: 55A2643C2A: from=<root@svrcrm.dcm-arobase.ci>, 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: <root@svrcrm.dcm-arobase.ci> -> <postmaster@domain.tld>, 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, <root@svrcrm.dcm-arobase.ci> -> <postmaster@domain.tld>, Message-ID: <20070720000001.55A2643C2A@svrcrm.dcm-arobase.ci>, 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=<postmaster@domain.tld>, 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: <root@svrcrm.dcm-arobase.ci> -> <postmaster@dcm-arobase.ci>, 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, <root@svrcrm.dcm-arobase.ci> -> <postmaster@dcm-arobase.ci>, quarantine: badh-Da56Uka0e6wX, Message-ID: <20070720070001.9F31943C2E@svrcrm.dcm-arobase.ci>, mail_id: Da56Uka0e6wX, Hits: 5.988, 15012 ms
Jul 23 10:22:35 svrcrm postfix/smtp[2984]: 9F31943C2E: to=<postmaster@dcm-arobase.ci>, 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=<root@svrcrm.dcm-arobase.ci>, 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: <root@svrcrm.dcm-arobase.ci> -> <crmadmins@dcm-arobase.ci>,<kdclaver@gmail.com>, 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, <root@svrcrm.dcm-arobase.ci> -> <crmadmins@dcm-arobase.ci>,<kdclaver@gmail.com>, Message-ID: <20070723102127.215D143C48@svrcrm.dcm-arobase.ci>, 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=<crmadmins@dcm-arobase.ci>, 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=<kdclaver@gmail.com>, 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

falko 24th July 2007 13:59

Quote:

Jul 23 10:27:27 svrcrm postfix/smtp[3012]: 215D143C48: to=<crmadmins@dcm-arobase.ci>, 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))
Can you post the output of
Code:

netstat -tap
? What's in /etc/postfix/master.cf?

kdclaver 24th July 2007 16:00

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 *:pop3s *:* LISTEN 11275/couriertcpd
tcp6 0 0 *:pop3 *:* 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

falko 25th July 2007 16:18

Looks ok. Can you try to reboot the system?

kdclaver 25th July 2007 17:32

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, <root@svrcrm.dcm-arobase.ci> -> <survey@popcon.debian.org>, Message-ID: <20070722064713.DBA3943C3C@svrcrm.dcm-arobase.ci>, mail_id: xM1k-AikxknM, Hits: 2.424, 8121 ms
Jul 25 15:27:38 svrcrm postfix/smtp[24148]: DBA3943C3C: to=<survey@popcon.debian.org>, 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=<root@svrcrm.dcm-arobase.ci>, 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: <root@svrcrm.dcm-arobase.ci> -> <postmaster@dcm-arobase.ci>, 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, <root@svrcrm.dcm-arobase.ci> -> <postmaster@dcm-arobase.ci>, quarantine: badh-iAmMbKtkeD5T, Message-ID: <20070721070002.05C6043C34@svrcrm.dcm-arobase.ci>, mail_id: iAmMbKtkeD5T, Hits: 6.066, 10232 ms
Jul 25 15:32:41 svrcrm postfix/smtp[24165]: 05C6043C34: to=<postmaster@dcm-arobase.ci>, 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))

falko 26th July 2007 19:45

Still the same error (bad file descriptor). Maybe a problem with your hard disk?


All times are GMT +2. The time now is 16:49.

Powered by vBulletin® Version 3.8.7
Copyright ©2000 - 2014, vBulletin Solutions, Inc.