Unable to send and receive mail in command line mode

Discussion in 'Installation/Configuration' started by kdclaver, Jul 18, 2007.

  1. kdclaver

    kdclaver New Member

    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]
     
  2. falko

    falko Super Moderator Howtoforge Staff Moderator HowtoForge Supporter ISPConfig Developer

    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.
     
  3. kdclaver

    kdclaver New Member

    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
     
  4. falko

    falko Super Moderator Howtoforge Staff Moderator HowtoForge Supporter ISPConfig Developer

    Can you post the output of
    Code:
    netstat -tap
    ? What's in /etc/postfix/master.cf?
     
  5. kdclaver

    kdclaver New Member

    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
     
  6. falko

    falko Super Moderator Howtoforge Staff Moderator HowtoForge Supporter ISPConfig Developer

    Looks ok. Can you try to reboot the system?
     
  7. kdclaver

    kdclaver New Member

    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))
     
  8. falko

    falko Super Moderator Howtoforge Staff Moderator HowtoForge Supporter ISPConfig Developer

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

Share This Page