Postfix error inbound & outbond

[plucchetti]

I've ISPConfig running under CentOs and i can't send and receive mails.
In postfix queue are all mails whit this messages: delivery temporarily suspended: connect to 127.0.0.1[127.0.0.1]: Connection refused
Maybe amavisd is the problem because the daemon start and stop after seconds.

Any help?


Thanks in advance

[till]

Plesae post an excerpt of your mail log.

[plucchetti]

This is my mail log:
May 29 00:29:26 hsimail postfix/qmgr[8638]: B0A6FAD056E: to=<hamanassi@hotmail.com>, relay=none, delay=3, status=deferred (delivery temporarily suspended: connect to 127.0.0.1[127.0.0.1]: Connection refused)
May 29 00:29:26 hsimail postfix/qmgr[8638]: B0A6FAD056E: to=<guischreiber@infovia.com.ar>, relay=none, delay=3, status=deferred (delivery temporarily suspended: connect to 127.0.0.1[127.0.0.1]: Connection refused)
May 29 00:29:26 hsimail postfix/qmgr[8638]: B0A6FAD056E: to=<hgfhj@kkk.com>, relay=none, delay=3, status=deferred (delivery temporarily suspended: connect to 127.0.0.1[127.0.0.1]: Connection refused)
May 29 00:29:26 hsimail postfix/qmgr[8638]: B0A6FAD056E: to=<guri_fracchia@mailcity.com>, relay=none, delay=3, status=deferred (delivery temporarily suspended: connect to 127.0.0.1[127.0.0.1]: Connection refused)
May 29 00:29:26 hsimail postfix/qmgr[8638]: B0A6FAD056E: to=<guasuncho@mixmail.com>, relay=none, delay=3, status=deferred (delivery temporarily suspended: connect to 127.0.0.1[127.0.0.1]: Connection refused)
May 29 00:29:26 hsimail postfix/qmgr[8638]: B0A6FAD056E: to=<hasting@topmail.com.ar>, relay=none, delay=3, status=deferred (delivery temporarily suspended: connect to 127.0.0.1[127.0.0.1]: Connection refused)
May 29 00:29:26 hsimail postfix/qmgr[8638]: B0A6FAD056E: to=<hermetico@web-mail.com.ar>, relay=none, delay=3, status=deferred (delivery temporarily suspended: connect to 127.0.0.1[127.0.0.1]: Connection refused)
May 29 00:29:26 hsimail postfix/qmgr[8638]: B0A6FAD056E: to=<guadiaf@yahoo.com>, relay=none, delay=3, status=deferred (delivery temporarily suspended: connect to 127.0.0.1[127.0.0.1]: Connection refused)

[plucchetti]

Till,

I've found in mailog messages of amavis when i tried to start, look:
May 29 07:10:58 hsimail amavis[8402]: starting. /usr/sbin/amavisd at mail.ibap.com.ar amavisd-new-2.4.2 (20060627), Unicode aware
May 29 07:10:58 hsimail amavis[8402]: Perl version 5.008005
May 29 07:10:59 hsimail amavis[8407]: Module Amavis::Conf 2.068
May 29 07:10:59 hsimail amavis[8407]: Module Archive::Tar 1.08
May 29 07:10:59 hsimail amavis[8407]: Module Archive::Zip 1.14
May 29 07:10:59 hsimail amavis[8407]: Module BerkeleyDB 0.26
May 29 07:10:59 hsimail amavis[8407]: Module Compress::Zlib 1.37
May 29 07:10:59 hsimail amavis[8407]: Module Convert::TNEF 0.17
May 29 07:10:59 hsimail amavis[8407]: Module Convert::UUlib 1.051
May 29 07:10:59 hsimail amavis[8407]: Module DBD::mysql 2.9004
May 29 07:10:59 hsimail amavis[8407]: Module DBI 1.40
May 29 07:10:59 hsimail amavis[8407]: Module DB_File 1.809
May 29 07:10:59 hsimail amavis[8407]: Module Digest::MD5 2.33
May 29 07:10:59 hsimail amavis[8407]: Module MIME::Entity 5.420
May 29 07:10:59 hsimail amavis[8407]: Module MIME::Parser 5.420
May 29 07:10:59 hsimail amavis[8407]: Module MIME::Tools 5.420
May 29 07:10:59 hsimail amavis[8407]: Module Mail::Header 1.74
May 29 07:10:59 hsimail amavis[8407]: Module Mail::Internet 1.74
May 29 07:10:59 hsimail amavis[8407]: Module Mail::SPF::Query 1.997
May 29 07:10:59 hsimail amavis[8407]: Module Mail::SpamAssassin 3.001008
May 29 07:10:59 hsimail amavis[8407]: Module Net::Cmd 2.26
May 29 07:10:59 hsimail amavis[8407]: Module Net::DNS 0.48
May 29 07:10:59 hsimail amavis[8407]: Module Net::SMTP 2.29
May 29 07:10:59 hsimail amavis[8407]: Module Net::Server 0.90
May 29 07:10:59 hsimail amavis[8407]: Module Razor2::Client::Version 2.77
May 29 07:10:59 hsimail amavis[8407]: Module Time::HiRes 1.55
May 29 07:10:59 hsimail amavis[8407]: Module Unix::Syslog 0.100
May 29 07:10:59 hsimail amavis[8407]: Amavis::DB code loaded
May 29 07:10:59 hsimail amavis[8407]: Amavis::Cache code loaded
May 29 07:10:59 hsimail amavis[8407]: SQL base code NOT loaded
May 29 07:10:59 hsimail amavis[8407]: SQL::Log code NOT loaded
May 29 07:10:59 hsimail amavis[8407]: SQL::Quarantine NOT loaded
May 29 07:10:59 hsimail amavis[8407]: Lookup::SQL code NOT loaded
May 29 07:10:59 hsimail amavis[8407]: Lookup::LDAP code NOT loaded
May 29 07:10:59 hsimail amavis[8407]: AM.PDP-in proto code loaded
May 29 07:10:59 hsimail amavis[8407]: SMTP-in proto code loaded
May 29 07:10:59 hsimail amavis[8407]: Courier proto code NOT loaded
May 29 07:10:59 hsimail amavis[8407]: SMTP-out proto code loaded
May 29 07:10:59 hsimail amavis[8407]: Pipe-out proto code NOT loaded
May 29 07:10:59 hsimail amavis[8407]: BSMTP-out proto code NOT loaded
May 29 07:10:59 hsimail amavis[8407]: Local-out proto code loaded
May 29 07:10:59 hsimail amavis[8407]: OS_Fingerprint code NOT loaded
May 29 07:10:59 hsimail amavis[8407]: ANTI-VIRUS code loaded
May 29 07:10:59 hsimail amavis[8407]: ANTI-SPAM code loaded
May 29 07:10:59 hsimail amavis[8407]: ANTI-SPAM-SA code loaded
May 29 07:10:59 hsimail amavis[8407]: Unpackers code loaded
May 29 07:10:59 hsimail amavis[8407]: Found $file at /usr/bin/file
May 29 07:10:59 hsimail amavis[8407]: No $dspam, not using it
May 29 07:10:59 hsimail amavis[8407]: Internal decoder for .mail
May 29 07:10:59 hsimail amavis[8407]: Internal decoder for .asc
May 29 07:10:59 hsimail amavis[8407]: Internal decoder for .uue
May 29 07:10:59 hsimail amavis[8407]: Internal decoder for .hqx
May 29 07:10:59 hsimail amavis[8407]: Internal decoder for .ync
May 29 07:10:59 hsimail amavis[8407]: Found decoder for .F at /usr/bin/unfreeze
May 29 07:10:59 hsimail amavis[8407]: Found decoder for .Z at /usr/bin/gzip -d
May 29 07:10:59 hsimail amavis[8407]: Found decoder for .gz at /usr/bin/gzip -d
May 29 07:10:59 hsimail amavis[8407]: Found decoder for .bz2 at /usr/bin/bzip2 -d
May 29 07:10:59 hsimail amavis[8407]: Found decoder for .lzo at /usr/bin/lzop -d
May 29 07:10:59 hsimail amavis[8407]: Found decoder for .rpm at /usr/bin/rpm2cpio
May 29 07:10:59 hsimail amavis[8407]: Found decoder for .cpio at /bin/cpio
May 29 07:10:59 hsimail amavis[8407]: Found decoder for .tar at /bin/cpio
May 29 07:10:59 hsimail amavis[8407]: Found decoder for .deb at /usr/bin/ar
May 29 07:10:59 hsimail amavis[8407]: Internal decoder for .zip
May 29 07:10:59 hsimail amavis[8407]: No decoder for .rar tried: rar, unrar
May 29 07:10:59 hsimail amavis[8407]: No decoder for .arj tried: arj, unarj
May 29 07:10:59 hsimail amavis[8407]: Found decoder for .arc at /usr/bin/nomarch
May 29 07:10:59 hsimail amavis[8407]: No decoder for .zoo tried: zoo, unzoo
May 29 07:10:59 hsimail amavis[8407]: Found decoder for .lha at /usr/bin/lha
May 29 07:10:59 hsimail amavis[8407]: Found decoder for .cab at /usr/bin/cabextract
May 29 07:10:59 hsimail amavis[8407]: No decoder for .tnef tried: tnef
May 29 07:10:59 hsimail amavis[8407]: Internal decoder for .tnef
May 29 07:10:59 hsimail amavis[8407]: Found decoder for .exe at /usr/bin/lha
May 29 07:10:59 hsimail amavis[8407]: Using internal av scanner code for (primary) ClamAV-clamd
May 29 07:10:59 hsimail amavis[8407]: (!!) TROUBLE in pre_loop_hook: db_init: BDB bad db env. at /var/spool/amavisd/db: Invalid argument, . at (eval 50) line 245.

[falko]

What's the output of netstat -tap?
Does mail delivery work when you disable amavisd?

[plucchetti]

Falko,

Problem solved, I did change mydestination in postfix master.cf to 127.0.0.1, now i've another problem, postfix mail queue has a lot of mails to deliver.
Here top command output:

Cpu(s): 94.3% us, 5.7% sy, 0.0% ni, 0.0% id, 0.0% wa, 0.0% hi, 0.0% si
Mem: 1573036k total, 1355912k used, 217124k free, 58344k buffers
Swap: 524280k total, 12016k used, 512264k free, 928420k cached

PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND
26184 hdurazzi 25 0 25856 22m 2180 R 33.3 1.5 0:01.23 spamassassin
26180 mdurazzi 25 0 25856 22m 2180 R 32.3 1.5 0:01.32 spamassassin
26195 ngarcia. 24 0 10528 8616 1828 R 10.0 0.5 0:00.30 spamassassin
22719 named 15 0 15604 6336 1868 S 1.3 0.4 0:02.88 named
22721 named 15 0 15604 6336 1868 S 0.3 0.4 0:00.79 named
26194 ngarcia. 21 0 1772 268 132 S 0.3 0.0 0:00.01 procmail
1 root 16 0 1688 600 516 S 0.0 0.0 0:00.20 init
2 root RT 0 0 0 0 S 0.0 0.0 0:00.00 migration/0
3 root 34 19 0 0 0 S 0.0 0.0 0:00.16 ksoftirqd/0
4 root RT 0 0 0 0 S 0.0 0.0 0:00.00 watchdog/0
5 root 10 -5 0 0 0 S 0.0 0.0 0:00.01 events/0
6 root 10 -5 0 0 0 S 0.0 0.0 0:00.98 khelper
7 root 10 -5 0 0 0 S 0.0 0.0 0:00.01 kthread
8 root 10 -5 0 0 0 S 0.0 0.0 0:00.00 xenwatch
9 root 10 -5 0 0 0 S 0.0 0.0 0:00.00 xenbus
15 root 10 -5 0 0 0 S 0.0 0.0 0:00.00 kblockd/0
19 root 20 -5 0 0 0 S 0.0 0.0 0:00.00 khubd
63 root 20 -5 0 0 0 S 0.0 0.0 0:00.00 aio/0
62 root 15 0 0 0 0 S 0.0 0.0 0:01.05 kswapd0
579 root 10 -5 0 0 0 S 0.0 0.0 0:00.00 kseriod
658 root 16 -5 0 0 0 S 0.0 0.0 0:00.00 kpsmoused
1308 root 10 -5 1576 460 400 S 0.0 0.0 0:00.01 udevd
1789 root 16 0 1592 536 448 S 0.0 0.0 0:15.02 syslogd
1793 root 16 0 1544 368 300 S 0.0 0.0 0:00.01 klogd
1875 root 17 0 4076 1124 824 S 0.0 0.1 0:00.41 sshd
1890 ntp 16 0 4452 4452 3436 S 0.0 0.3 0:00.25 ntpd
1951 root 20 0 2208 1116 956 S 0.0 0.1 0:00.00 mysqld_safe
1981 mysql 16 0 52948 16m 3572 S 0.0 1.0 0:01.10 mysqld
2001 mysql 16 0 52948 16m 3572 S 0.0 1.0 0:01.32 mysqld
2002 mysql 20 0 52948 16m 3572 S 0.0 1.0 0:00.00 mysqld
2003 mysql 16 0 52948 16m 3572 S 0.0 1.0 0:00.00 mysqld
2004 mysql 16 0 52948 16m 3572 S 0.0 1.0 0:00.00 mysqld
2005 mysql 20 0 52948 16m 3572 S 0.0 1.0 0:00.00 mysqld
2006 mysql 16 0 52948 16m 3572 S 0.0 1.0 0:00.53 mysqld
2007 mysql 16 0 52948 16m 3572 S 0.0 1.0 0:00.42 mysqld
2008 mysql 16 0 52948 16m 3572 S 0.0 1.0 0:00.00 mysqld
2009 mysql 16 0 52948 16m 3572 S 0.0 1.0 0:15.51 mysqld
2023 root 15 0 3564 768 644 S 0.0 0.0 0:12.27 dovecot
2033 root 17 0 6312 1832 1168 S 0.0 0.1 0:22.12 dovecot-auth
2050 root 16 0 30440 24m 2436 S 0.0 1.6 0:01.36 spamd
2204 root 16 0 2476 892 524 S 0.0 0.1 0:00.02 crond

If you want anohter data, just tell me.

Thanks a lot.

[till]

This output just means that your system resources were used to scan the emails that were delivered. Thats ok.