1

Topic: iRedMail on CentOS 7

- iRedMail version: 0.9.0
- Store mail accounts in which backend (LDAP/MySQL/PGSQL): MySQL
- Linux/BSD distribution name and version: CentOS 7 minimal, Nginx
- Related log if you're reporting an issue:

It is a new fresh install of iredmail. Nothing added!
The system is not able to send any email.

I have checked the email queue and one email is there:
connect to 127.0.0.1[127.0.0.1]:10024: Connection refused

----

Spider Email Archiver: On-Premises, lightweight email archiving software developed by iRedMail team. Supports Amazon S3 compatible storage and custom branding.

2

Re: iRedMail on CentOS 7

sepid wrote:

connect to 127.0.0.1[127.0.0.1]:10024: Connection refused

Amavisd service is not running. Please start it first.

== UPDATE ==

I added a tutorial to collect errors you may get while maintaining iRedMail servers here, it's just a beginning, will add more in the future:
http://www.iredmail.org/docs/errors.html

3

Re: iRedMail on CentOS 7

[root@mail ~]# service amavisd restart
Redirecting to /bin/systemctl restart  amavisd.service
Job for amavisd.service failed. See 'systemctl status amavisd.service' and 'journalctl -xn' for details.
[root@mail ~]# systemctl status amavisd.service
amavisd.service - Amavisd-new is an interface between MTA and content checkers.
   Loaded: loaded (/usr/lib/systemd/system/amavisd.service; enabled)
   Active: failed (Result: start-limit) since Sun 2015-02-01 22:55:50 EST; 38s ago
     Docs: http://www.ijs.si/software/amavisd/#doc
  Process: 6013 ExecStart=/usr/sbin/amavisd -c /etc/amavisd/amavisd.conf (code=exited, status=227/NO_NEW_PRIVILEGES)

Feb 01 22:55:50 mail systemd[1]: Failed to start Amavisd-new is an interface between MTA and content checkers..
Feb 01 22:55:50 mail systemd[1]: Unit amavisd.service entered failed state.
Feb 01 22:55:50 mail systemd[1]: amavisd.service holdoff time over, scheduling restart.
Feb 01 22:55:50 mail systemd[1]: Stopping Amavisd-new is an interface between MTA and content checkers....
Feb 01 22:55:50 mail systemd[1]: Starting Amavisd-new is an interface between MTA and content checkers....
Feb 01 22:55:50 mail systemd[1]: amavisd.service start request repeated too quickly, refusing to start.
Feb 01 22:55:50 mail systemd[1]: Failed to start Amavisd-new is an interface between MTA and content checkers..
Feb 01 22:55:50 mail systemd[1]: Unit amavisd.service entered failed state.


It is a new fresh installation. Is it anything wrong with my system? Or is it a bug for installing iredmail on CentOS 7 ?

4

Re: iRedMail on CentOS 7

This is a bug in old version of amavisd, but default iRedMail installation should install the latest amavisd from EPEL repository which doesn't has this issue. Could you please show us output of below command?

# rpm -qi amavisd-new

5

Re: iRedMail on CentOS 7

[root@mail ~]# rpm -qi amavisd-new
Name        : amavisd-new
Version     : 2.9.1
Release     : 5.el7
Architecture: noarch
Install Date: Sun 01 Feb 2015 03:48:25 PM EST
Group       : Applications/System
Size        : 3105963
License     : GPLv2+ and BSD and GFDL
Signature   : RSA/SHA256, Wed 20 Aug 2014 06:07:05 PM EDT, Key ID 6a2faea2352c64e5
Source RPM  : amavisd-new-2.9.1-5.el7.src.rpm
Build Date  : Wed 20 Aug 2014 09:26:15 AM EDT
Build Host  : buildvm-24.phx2.fedoraproject.org
Relocations : (not relocatable)
Packager    : Fedora Project
Vendor      : Fedora Project
URL         : http://www.ijs.si/software/amavisd/
Summary     : Email filter with virus scanner and spamassassin support
Description :
amavisd-new is a high-performance and reliable interface between mailer
(MTA) and one or more content checkers: virus scanners, and/or
Mail::SpamAssassin Perl module. It is written in Perl, assuring high
reliability, portability and maintainability. It talks to MTA via (E)SMTP
or LMTP, or by using helper programs. No timing gaps exist in the design
which could cause a mail loss.

6

Re: iRedMail on CentOS 7

Please try this:

# service amavisd stop
# service amavisd debug

Then check Postfix log file and try to extract more detailed error log.

7

Re: iRedMail on CentOS 7

I have the same issue. I recently installed iredmail 0.9.0 on clean VPS with CentOS 7, with settings like in sepid's first post, and the same problems with amavisd. I tried to update amavisd from 2.9.1(latest epel version) to version 2.10.0 from other repo, and it didn't helped, getting the same error.
For test purpose I reinstalled my VPS to Ubuntu 14.10, and then installed again iredmail 0.9.0, it worked like a charm. Btw I noticed that on Ubuntu installed older version of amavisd than on CentOS 7.

8

Re: iRedMail on CentOS 7

Please show me output of below command:

# ls -l /etc/amavisd/

File /etc/amavisd/amavisd.conf must be owned by 'root:amavis' with permission 0640.

I got the similar Amavisd issue when i initially ported iRedMail to work on CentOS 7, but it turned out it's a bug in EPEL Amavisd package, and fixed by new version in EPEL.

I checked amavisd package on my CentOS 7 testing machine, same version, but it works fine.

9

Re: iRedMail on CentOS 7

The output is:
root@mx ~]# ls -l /etc/amavisd/total 64-rw-r----- 1 root amavis 25007 Feb  3 21:54 amavisd.conf-rw-r--r-- 1 root root   36731 Feb  3 21:54 amavisd.conf.2015.02.03.21.46.30[root@mx ~]#

10

Re: iRedMail on CentOS 7

And this is output for amavisd -V :
amavisd -Vamavisd-new-2.9.1 (20140627)

I reinstalled back CentOS 7 back from Ubuntu, and this version of amavisd installed with iredmail 0.9.0

11

Re: iRedMail on CentOS 7

lito wrote:

-rw-r----- 1 root amavis 25007 Feb  3 21:54 amavisd.conf

This permission looks just fine.

Any error log if you run 'service amavisd debug'?

12

Re: iRedMail on CentOS 7

I tried to run with command "service amavisd debug" and it doesn't run, it said that wrong command. So I used other command " amavisd -u amavis debug", and I got this output: Config file "/etc/amavisd.conf" does not exist, at /usr/sbin/amavisd line 2148.

13

Re: iRedMail on CentOS 7

Can you please also make fresh install of CentOS 7 and install there iredmail to test this bug?

14

Re: iRedMail on CentOS 7

Try again with '-c' option:

# amavisd -u amavis -c /etc/amavisd/amavisd.conf debug

15

Re: iRedMail on CentOS 7

I sent you debug output via PM.

16

Re: iRedMail on CentOS 7

Please don't send private message, just paste the output here.

17

Re: iRedMail on CentOS 7

I'm sorry that I sent output via PM, but I did it because for this moment I don't have access to my PC, and I done it with mobile phone. My output contains some private information like name of my domains, and it was difficult to masquerade or cut it with mobile phone, because output is really big, later I'll paste my output here when I'll reach my PC.

18

Re: iRedMail on CentOS 7

Attaching debug here after few hours in debug mode:
# amavisd -u amavis -c /etc/amavisd/amavisd.conf debug
Feb  5 13:47:30.134 xx.xxxxx.com /usr/sbin/amavisd[4715]: logging initiali                                                                                                                                                             zed, log level 0, syslog: amavis.mail
Feb  5 13:47:30.139 xx.xxxxx.com /usr/sbin/amavisd[4715]: run_command: [47                                                                                                                                                             16] /usr/bin/uptime </dev/null 2>/dev/null
Feb  5 13:47:30.150 xx.xxxxx.com /usr/sbin/amavisd[4715]: system uptime 1                                                                                                                                                              15:50:00:  13:47:30 up 1 day, 15:50,  1 user,  load average: 0.00, 0.01, 0.00
Feb  5 13:47:30.151 xx.xxxxx.com /usr/sbin/amavisd[4715]: Valid PID file (                                                                                                                                                             younger than sys uptime 1 15:50:00)
Feb  5 13:47:30.151 xx.xxxxx.com /usr/sbin/amavisd[4715]: starting. /usr/s                                                                                                                                                             bin/amavisd at xx.xxxxx.com amavisd-new-2.9.1 (20140627), Unicode aware, L                                                                                                                                                             ANG="en_US.UTF-8"
Feb  5 13:47:30.152 xx.xxxxx.com /usr/sbin/amavisd[4715]: perl=5.016003, u                                                                                                                                                             ser=amavis, EUID: 995 (995);  group=, EGID: 994 (994)
Feb  5 13:47:30.247 xx.xxxxx.com /usr/sbin/amavisd[4715]: INFO: no optiona                                                                                                                                                             l modules: unicore::lib::Nt::De.pl Unix::Getrusage
Feb  5 13:47:30.247 xx.xxxxx.com /usr/sbin/amavisd[4715]: SpamControl: att                                                                                                                                                             empting to load scanner SpamAssassin, module Amavis::SpamControl::SpamAssassin
Feb  5 13:47:30.247 xx.xxxxx.com /usr/sbin/amavisd[4715]: SpamControl: sca                                                                                                                                                             nner SpamAssassin, module Amavis::SpamControl::SpamAssassin
Feb  5 13:47:30.675 xx.xxxxx.com /usr/sbin/amavisd[4715]: INFO: SA version                                                                                                                                                             : 3.3.2, 3.003002, no optional modules: Net::CIDR::Lite Image::Info Image::Info:                                                                                                                                                             :GIF Image::Info::JPEG Image::Info::PNG Image::Info::BMP Image::Info::TIFF
Feb  5 13:47:30.675 xx.xxxxx.com /usr/sbin/amavisd[4715]: SpamControl: ini                                                                                                                                                             t_pre_chroot on SpamAssassin done
Feb  5 13:47:30.675 xx.xxxxx.com /usr/sbin/amavisd[4715]: socket module IO                                                                                                                                                             ::Socket::IP, protocol families available: INET, INET6
Feb  5 13:47:30.676 xx.xxxxx.com /usr/sbin/amavisd[4715]: bind to /var/spo                                                                                                                                                             ol/amavisd/amavisd.sock|unix, 127.0.0.1:10024/tcp, [::1]:10024/tcp, 127.0.0.1:99                                                                                                                                                             98/tcp, [::1]:9998/tcp
Pid_file "/var/run/amavisd/amavisd.pid" already exists.  Overwriting!
Feb  5 13:47:30.677 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: 201                                                                                                                                                             5/02/05-13:47:30 Amavis (type Net::Server::PreForkSimple) starting! pid(4715)
Feb  5 13:47:30.682 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: Bin                                                                                                                                                             ding to UNIX socket file "/var/spool/amavisd/amavisd.sock"
Feb  5 13:47:30.682 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: Bin                                                                                                                                                             ding to TCP port 10024 on host 127.0.0.1 with IPv4
Feb  5 13:47:30.683 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: Bin                                                                                                                                                             ding to TCP port 10024 on host ::1 with IPv6
Feb  5 13:47:30.683 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: Bin                                                                                                                                                             ding to TCP port 9998 on host 127.0.0.1 with IPv4
Feb  5 13:47:30.683 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: Bin                                                                                                                                                             ding to TCP port 9998 on host ::1 with IPv6
Feb  5 13:47:30.684 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: Gro                                                                                                                                                             up Not Defined.  Defaulting to EGID '994'
Feb  5 13:47:30.684 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: Use                                                                                                                                                             r Not Defined.  Defaulting to EUID '995'
Feb  5 13:47:30.684 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: Set                                                                                                                                                             ting up serialization via flock
Feb  5 13:47:30.685 xx.xxxxx.com /usr/sbin/amavisd[4715]: after_chroot_ini                                                                                                                                                             t: EUID: 995 (995);  EGID: 994 (994)
Feb  5 13:47:30.685 xx.xxxxx.com /usr/sbin/amavisd[4715]: config files rea                                                                                                                                                             d: /etc/amavisd/amavisd.conf
Feb  5 13:47:30.710 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Amavis::C                                                                                                                                                             onf        2.321
Feb  5 13:47:30.710 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Archive::                                                                                                                                                             Zip        1.30
Feb  5 13:47:30.710 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module BerkeleyD                                                                                                                                                             B          0.51
Feb  5 13:47:30.710 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Compress:                                                                                                                                                             :Raw::Zlib 2.061
Feb  5 13:47:30.710 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Compress:                                                                                                                                                             :Zlib      2.061
Feb  5 13:47:30.711 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Crypt::Op                                                                                                                                                             enSSL::RSA 0.28
Feb  5 13:47:30.711 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module DBD::mysq                                                                                                                                                             l          4.023
Feb  5 13:47:30.711 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module DBI                                                                                                                                                                              1.627
Feb  5 13:47:30.711 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module DB_File                                                                                                                                                                          1.83
Feb  5 13:47:30.711 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Digest::M                                                                                                                                                             D5         2.52
Feb  5 13:47:30.712 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Digest::S                                                                                                                                                             HA         5.85
Feb  5 13:47:30.712 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Encode                                                                                                                                                                           2.51
Feb  5 13:47:30.712 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module File::Tem                                                                                                                                                             p          0.2301
Feb  5 13:47:30.713 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module IO::Socke                                                                                                                                                             t::INET6   2.69
Feb  5 13:47:30.713 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module IO::Socke                                                                                                                                                             t::IP      0.21
Feb  5 13:47:30.713 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module MIME::Ent                                                                                                                                                             ity        5.505
Feb  5 13:47:30.713 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module MIME::Par                                                                                                                                                             ser        5.505
Feb  5 13:47:30.714 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module MIME::Too                                                                                                                                                             ls         5.505
Feb  5 13:47:30.714 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Mail::DKI                                                                                                                                                             M::Signer  0.39
Feb  5 13:47:30.714 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Mail::DKI                                                                                                                                                             M::Verifier 0.39
Feb  5 13:47:30.714 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Mail::Hea                                                                                                                                                             der        2.12
Feb  5 13:47:30.714 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Mail::Int                                                                                                                                                             ernet      2.12
Feb  5 13:47:30.714 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Mail::SPF                                                                                                                                                                        v2.008
Feb  5 13:47:30.714 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Mail::Spa                                                                                                                                                             mAssassin  3.003002
Feb  5 13:47:30.715 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Net::DNS                                                                                                                                                                         0.72
Feb  5 13:47:30.715 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Net::Serv                                                                                                                                                             er         2.007
Feb  5 13:47:30.715 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module NetAddr::                                                                                                                                                             IP         4.069
Feb  5 13:47:30.715 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Razor2::C                                                                                                                                                             lient::Version 2.84
Feb  5 13:47:30.715 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Scalar::U                                                                                                                                                             til        1.27
Feb  5 13:47:30.716 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Socket                                                                                                                                                                           2.010
Feb  5 13:47:30.716 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Socket6                                                                                                                                                                          0.23
Feb  5 13:47:30.716 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Time::HiR                                                                                                                                                             es         1.9725
Feb  5 13:47:30.716 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module URI                                                                                                                                                                              1.60
Feb  5 13:47:30.716 xx.xxxxx.com /usr/sbin/amavisd[4715]: Module Unix::Sys                                                                                                                                                             log        1.1
Feb  5 13:47:30.716 xx.xxxxx.com /usr/sbin/amavisd[4715]: Amavis::ZMQ code                                                                                                                                                                  NOT loaded
Feb  5 13:47:30.716 xx.xxxxx.com /usr/sbin/amavisd[4715]: Amavis::DB code                                                                                                                                                                   loaded
Feb  5 13:47:30.716 xx.xxxxx.com /usr/sbin/amavisd[4715]: SQL base code                                                                                                                                                                     loaded
Feb  5 13:47:30.716 xx.xxxxx.com /usr/sbin/amavisd[4715]: SQL::Log code                                                                                                                                                                     loaded
Feb  5 13:47:30.716 xx.xxxxx.com /usr/sbin/amavisd[4715]: SQL::Quarantine                                                                                                                                                                   loaded
Feb  5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: Lookup::SQL code                                                                                                                                                                  loaded
Feb  5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: Lookup::LDAP cod                                                                                                                                                             e    NOT loaded
Feb  5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: AM.PDP-in proto                                                                                                                                                              code loaded
Feb  5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: SMTP-in proto co                                                                                                                                                             de   loaded
Feb  5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: Courier proto co                                                                                                                                                             de   NOT loaded
Feb  5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: SMTP-out proto c                                                                                                                                                             ode  loaded
Feb  5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: Pipe-out proto c                                                                                                                                                             ode  NOT loaded
Feb  5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: BSMTP-out proto                                                                                                                                                              code NOT loaded
Feb  5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: Local-out proto                                                                                                                                                              code NOT loaded
Feb  5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: OS_Fingerprint c                                                                                                                                                             ode  NOT loaded
Feb  5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: ANTI-VIRUS code                                                                                                                                                                   loaded
Feb  5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: ANTI-SPAM code                                                                                                                                                                    loaded
Feb  5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: ANTI-SPAM-EXT co                                                                                                                                                             de   NOT loaded
Feb  5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: ANTI-SPAM-C code                                                                                                                                                                  NOT loaded
Feb  5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: ANTI-SPAM-SA cod                                                                                                                                                             e    loaded
Feb  5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: Unpackers code                                                                                                                                                                    loaded
Feb  5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: DKIM code                                                                                                                                                                         loaded
Feb  5 13:47:30.717 xx.xxxxx.com /usr/sbin/amavisd[4715]: Tools code                                                                                                                                                                        NOT loaded
Feb  5 13:47:30.718 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found $file                                                                                                                                                                         at /usr/bin/file
Feb  5 13:47:30.718 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found $altermime                                                                                                                                                                    at /usr/bin/altermime
Feb  5 13:47:30.718 xx.xxxxx.com /usr/sbin/amavisd[4715]: Internal decoder                                                                                                                                                              for .mail
Feb  5 13:47:30.718 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .F    at /usr/bin/unfreeze
Feb  5 13:47:30.718 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .Z    at /usr/bin/gzip -d
Feb  5 13:47:30.718 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .gz   at /usr/bin/gzip -d
Feb  5 13:47:30.718 xx.xxxxx.com /usr/sbin/amavisd[4715]: Internal decoder                                                                                                                                                              for .gz   (backup, not used)
Feb  5 13:47:30.718 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .bz2  at /usr/bin/bzip2 -d
Feb  5 13:47:30.718 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .xz   at /usr/bin/xzdec
Feb  5 13:47:30.719 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .lzma at /usr/bin/xz -dc --format=lzma
Feb  5 13:47:30.719 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .lrz  at /usr/bin/lrzip -q -k -d -o -
Feb  5 13:47:30.719 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .lzo  at /usr/bin/lzop -d
Feb  5 13:47:30.719 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .rpm  at /usr/bin/rpm2cpio
Feb  5 13:47:30.719 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .cpio at /usr/bin/cpio
Feb  5 13:47:30.719 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .tar  at /usr/bin/cpio
Feb  5 13:47:30.719 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .deb  at /usr/bin/ar
Feb  5 13:47:30.719 xx.xxxxx.com /usr/sbin/amavisd[4715]: No ext program f                                                                                                                                                             or   .rar, tried: unrar, rar
Feb  5 13:47:30.719 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .arj  at /usr/bin/unarj
Feb  5 13:47:30.719 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .arc  at /usr/bin/nomarch
Feb  5 13:47:30.720 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .zoo  at /usr/bin/unzoo
Feb  5 13:47:30.720 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .cab  at /usr/bin/cabextract
Feb  5 13:47:30.720 xx.xxxxx.com /usr/sbin/amavisd[4715]: Internal decoder                                                                                                                                                              for .tnef
Feb  5 13:47:30.720 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .zip  at /usr/bin/7za
Feb  5 13:47:30.720 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .kmz  at /usr/bin/7za
Feb  5 13:47:30.720 xx.xxxxx.com /usr/sbin/amavisd[4715]: Internal decoder                                                                                                                                                              for .zip  (backup, not used)
Feb  5 13:47:30.720 xx.xxxxx.com /usr/sbin/amavisd[4715]: Internal decoder                                                                                                                                                              for .kmz  (backup, not used)
Feb  5 13:47:30.720 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .7z   at /usr/bin/7za
Feb  5 13:47:30.720 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .7z   at /usr/bin/7za (backup, not used)
Feb  5 13:47:30.720 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .zip  at /usr/bin/7za (backup, not used)
Feb  5 13:47:30.720 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .gz   at /usr/bin/7za (backup, not used)
Feb  5 13:47:30.720 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .bz2  at /usr/bin/7za (backup, not used)
Feb  5 13:47:30.720 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .Z    at /usr/bin/7za (backup, not used)
Feb  5 13:47:30.720 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .tar  at /usr/bin/7za (backup, not used)
Feb  5 13:47:30.720 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .xz   at /usr/bin/7z (backup, not used)
Feb  5 13:47:30.721 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .lzma at /usr/bin/7z (backup, not used)
Feb  5 13:47:30.721 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .jar  at /usr/bin/7z
Feb  5 13:47:30.721 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .cpio at /usr/bin/7z (backup, not used)
Feb  5 13:47:30.721 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .arj  at /usr/bin/7z (backup, not used)
Feb  5 13:47:30.721 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .rar  at /usr/bin/7z
Feb  5 13:47:30.721 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .swf  at /usr/bin/7z
Feb  5 13:47:30.721 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .lha  at /usr/bin/7z
Feb  5 13:47:30.721 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .iso  at /usr/bin/7z
Feb  5 13:47:30.721 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .cab  at /usr/bin/7z (backup, not used)
Feb  5 13:47:30.721 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .deb  at /usr/bin/7z (backup, not used)
Feb  5 13:47:30.721 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .rpm  at /usr/bin/7z (backup, not used)
Feb  5 13:47:30.721 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found decoder fo                                                                                                                                                             r    .exe  at /usr/bin/unarj
Feb  5 13:47:30.721 xx.xxxxx.com /usr/sbin/amavisd[4715]: Using primary in                                                                                                                                                             ternal av scanner code for ClamAV-clamd
Feb  5 13:47:30.721 xx.xxxxx.com /usr/sbin/amavisd[4715]: Found secondary                                                                                                                                                              av scanner ClamAV-clamscan at /usr/bin/clamscan
Feb  5 13:47:30.721 xx.xxxxx.com /usr/sbin/amavisd[4715]: Using internal s                                                                                                                                                             pam scanner code for SpamAssassin
Feb  5 13:47:30.722 xx.xxxxx.com /usr/sbin/amavisd[4715]: Deleting db file                                                                                                                                                             s snmp.db,nanny.db,__db.001,__db.003,__db.002 in /var/spool/amavisd/db
Feb  5 13:47:30.724 xx.xxxxx.com /usr/sbin/amavisd[4715]: Creating db in /                                                                                                                                                             var/spool/amavisd/db/; BerkeleyDB 0.51, libdb 5.3
Feb  5 13:47:30.811 xx.xxxxx.com /usr/sbin/amavisd[4715]: initializing Mai                                                                                                                                                             l::SpamAssassin (0)
Feb  5 13:47:30.811 xx.xxxxx.com /usr/sbin/amavisd[4715]: SpamAssassin deb                                                                                                                                                             ug facilities: info
Feb  5 13:48:02.251 xx.xxxxx.com /usr/sbin/amavisd[4715]: SpamAssassin loaded plugins: AutoLearnThreshold, Bayes, BodyE                                                                                                                val, Check, DKIM, DNSEval, FreeMail, HTMLEval, HTTPSMismatch, Hashcash, HeaderEval, ImageInfo, MIMEEval, MIMEHeader, Pyzor, R                                                                                                                azor2, RelayEval, ReplaceTags, SPF, SpamCop, URIDNSBL, URIDetail, URIEval, VBounce, WLBLEval, WhiteListSubject
Feb  5 13:48:02.251 xx.xxxxx.com /usr/sbin/amavisd[4715]: SpamControl: init_pre_fork on SpamAssassin done
Feb  5 13:48:02.251 xx.xxxxx.com /usr/sbin/amavisd[4715]: extra modules loaded after daemonizing/chrooting: /usr/share/                                                                                                                perl5/Net/libnet.cfg, Mail/SpamAssassin/Plugin/FreeMail.pm, Mail/SpamAssassin/Plugin/SpamCop.pm, Net/Cmd.pm, Net/Config.pm, N                                                                                                                et/SMTP.pm
Feb  5 13:48:02.252 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: Beginning prefork (4 processes)
Feb  5 13:48:02.253 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: Starting "4" children
Feb  5 13:48:02.272 xx.xxxxx.com /usr/sbin/amavisd[4717]: Net::Server: Child Preforked (4717)
Feb  5 13:48:02.272 xx.xxxxx.com /usr/sbin/amavisd[4717]: entered child_init_hook
Feb  5 13:48:02.281 xx.xxxxx.com /usr/sbin/amavisd[4718]: Net::Server: Child Preforked (4718)
Feb  5 13:48:02.281 xx.xxxxx.com /usr/sbin/amavisd[4718]: entered child_init_hook
Feb  5 13:48:02.287 xx.xxxxx.com /usr/sbin/amavisd[4717]: storage and lookups will use the same connection to SQL
Feb  5 13:48:02.347 xx.xxxxx.com /usr/sbin/amavisd[4719]: Net::Server: Child Preforked (4719)
Feb  5 13:48:02.348 xx.xxxxx.com /usr/sbin/amavisd[4719]: entered child_init_hook
Feb  5 13:48:02.349 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: Parent ready for children.
Feb  5 13:48:02.357 xx.xxxxx.com /usr/sbin/amavisd[4717]: SpamControl: init_child on SpamAssassin done
Feb  5 13:48:02.357 xx.xxxxx.com /usr/sbin/amavisd[4718]: storage and lookups will use the same connection to SQL
Feb  5 13:48:02.359 xx.xxxxx.com /usr/sbin/amavisd[4720]: Net::Server: Child Preforked (4720)
Feb  5 13:48:02.360 xx.xxxxx.com /usr/sbin/amavisd[4720]: entered child_init_hook
Feb  5 13:48:02.361 xx.xxxxx.com /usr/sbin/amavisd[4718]: SpamControl: init_child on SpamAssassin done
Feb  5 13:48:02.363 xx.xxxxx.com /usr/sbin/amavisd[4719]: storage and lookups will use the same connection to SQL
Feb  5 13:48:02.369 xx.xxxxx.com /usr/sbin/amavisd[4720]: storage and lookups will use the same connection to SQL
Feb  5 13:48:02.371 xx.xxxxx.com /usr/sbin/amavisd[4719]: SpamControl: init_child on SpamAssassin done
Feb  5 13:48:02.371 xx.xxxxx.com /usr/sbin/amavisd[4720]: SpamControl: init_child on SpamAssassin done

^CFeb  5 21:27:34.856 xx.xxxxx.com /usr/sbin/amavisd[4718]: child_goes_idle (child finishing)
Feb  5 21:27:34.857 xx.xxxxx.com /usr/sbin/amavisd[4719]: child_goes_idle (child finishing)
Feb  5 21:27:34.857 xx.xxxxx.com /usr/sbin/amavisd[4719]: child_goes_idle: disconnected none (child finishing)
Feb  5 21:27:34.857 xx.xxxxx.com /usr/sbin/amavisd[4719]: SA rundown_child (0)
Feb  5 21:27:34.859 xx.xxxxx.com /usr/sbin/amavisd[4717]: child_goes_idle (child finishing)
Feb  5 21:27:34.859 xx.xxxxx.com /usr/sbin/amavisd[4717]: child_goes_idle: disconnected none (child finishing)
Feb  5 21:27:34.860 xx.xxxxx.com /usr/sbin/amavisd[4717]: SA rundown_child (0)
Feb  5 21:27:34.860 xx.xxxxx.com /usr/sbin/amavisd[4720]: child_goes_idle (child finishing)
Feb  5 21:27:34.860 xx.xxxxx.com /usr/sbin/amavisd[4720]: child_goes_idle: disconnected none (child finishing)
Feb  5 21:27:34.861 xx.xxxxx.com /usr/sbin/amavisd[4720]: SA rundown_child (0)
Feb  5 21:27:34.861 xx.xxxxx.com /usr/sbin/amavisd[4718]: child_goes_idle: disconnected none (child finishing)
Feb  5 21:27:34.861 xx.xxxxx.com /usr/sbin/amavisd[4718]: SA rundown_child (0)
Feb  5 21:27:34.863 xx.xxxxx.com /usr/sbin/amavisd[4717]: SpamControl: rundown_child on SpamAssassin done
Feb  5 21:27:34.863 xx.xxxxx.com /usr/sbin/amavisd[4717]: child_finish_hook: invoking DESTROY methods
Feb  5 21:27:34.863 xx.xxxxx.com /usr/sbin/amavisd[4717]: Amavis::Out::SQL::Log DESTROY called
Feb  5 21:27:34.863 xx.xxxxx.com /usr/sbin/amavisd[4717]: Amavis::Lookup::SQL DESTROY called
Feb  5 21:27:34.863 xx.xxxxx.com /usr/sbin/amavisd[4717]: Amavis::Lookup::SQL DESTROY called
Feb  5 21:27:34.863 xx.xxxxx.com /usr/sbin/amavisd[4717]: Amavis::Out::SQL::Connection DESTROY called
Feb  5 21:27:34.865 xx.xxxxx.com /usr/sbin/amavisd[4720]: SpamControl: rundown_child on SpamAssassin done
Feb  5 21:27:34.865 xx.xxxxx.com /usr/sbin/amavisd[4720]: child_finish_hook: invoking DESTROY methods
Feb  5 21:27:34.865 xx.xxxxx.com /usr/sbin/amavisd[4720]: Amavis::Out::SQL::Log DESTROY called
Feb  5 21:27:34.865 xx.xxxxx.com /usr/sbin/amavisd[4720]: Amavis::Lookup::SQL DESTROY called
Feb  5 21:27:34.865 xx.xxxxx.com /usr/sbin/amavisd[4720]: Amavis::Lookup::SQL DESTROY called
Feb  5 21:27:34.865 xx.xxxxx.com /usr/sbin/amavisd[4720]: Amavis::Out::SQL::Connection DESTROY called
Feb  5 21:27:34.866 xx.xxxxx.com /usr/sbin/amavisd[4718]: SpamControl: rundown_child on SpamAssassin done
Feb  5 21:27:34.867 xx.xxxxx.com /usr/sbin/amavisd[4718]: child_finish_hook: invoking DESTROY methods
Feb  5 21:27:34.867 xx.xxxxx.com /usr/sbin/amavisd[4719]: SpamControl: rundown_child on SpamAssassin done
Feb  5 21:27:34.867 xx.xxxxx.com /usr/sbin/amavisd[4719]: child_finish_hook: invoking DESTROY methods
Feb  5 21:27:34.867 xx.xxxxx.com /usr/sbin/amavisd[4719]: Amavis::Out::SQL::Log DESTROY called
Feb  5 21:27:34.867 xx.xxxxx.com /usr/sbin/amavisd[4719]: Amavis::Lookup::SQL DESTROY called
Feb  5 21:27:34.868 xx.xxxxx.com /usr/sbin/amavisd[4719]: Amavis::Lookup::SQL DESTROY called
Feb  5 21:27:34.868 xx.xxxxx.com /usr/sbin/amavisd[4718]: Amavis::Out::SQL::Log DESTROY called
Feb  5 21:27:34.868 xx.xxxxx.com /usr/sbin/amavisd[4718]: Amavis::Lookup::SQL DESTROY called
Feb  5 21:27:34.868 xx.xxxxx.com /usr/sbin/amavisd[4718]: Amavis::Lookup::SQL DESTROY called
Feb  5 21:27:34.868 xx.xxxxx.com /usr/sbin/amavisd[4718]: Amavis::Out::SQL::Connection DESTROY called
Feb  5 21:27:34.869 xx.xxxxx.com /usr/sbin/amavisd[4719]: Amavis::Out::SQL::Connection DESTROY called
Feb  5 21:27:34.870 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: 2015/02/05-21:27:34 Server closing!
Feb  5 21:27:34.870 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: Kill TERM pid 4718
Feb  5 21:27:34.871 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: Kill TERM pid 4719
Feb  5 21:27:34.871 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: Kill TERM pid 4717
Feb  5 21:27:34.871 xx.xxxxx.com /usr/sbin/amavisd[4715]: Net::Server: Kill TERM pid 4720
Feb  5 21:27:34.905 xx.xxxxx.com /usr/sbin/amavisd[4718]: child_goes_idle (child finishing)
Feb  5 21:27:34.905 xx.xxxxx.com /usr/sbin/amavisd[4718]: child_goes_idle: disconnected none (child finishing)
Feb  5 21:27:34.905 xx.xxxxx.com /usr/sbin/amavisd[4718]: SA rundown_child (0)
Feb  5 21:27:34.906 xx.xxxxx.com /usr/sbin/amavisd[4718]: SpamControl: rundown_child on SpamAssassin done
Feb  5 21:27:34.906 xx.xxxxx.com /usr/sbin/amavisd[4718]: child_finish_hook: invoking DESTROY methods
Feb  5 21:27:34.906 xx.xxxxx.com /usr/sbin/amavisd[4719]: child_goes_idle (child finishing)
Feb  5 21:27:34.906 xx.xxxxx.com /usr/sbin/amavisd[4719]: child_goes_idle: disconnected none (child finishing)
Feb  5 21:27:34.906 xx.xxxxx.com /usr/sbin/amavisd[4719]: SA rundown_child (0)
Feb  5 21:27:34.906 xx.xxxxx.com /usr/sbin/amavisd[4719]: SpamControl: rundown_child on SpamAssassin done
Feb  5 21:27:34.907 xx.xxxxx.com /usr/sbin/amavisd[4719]: child_finish_hook: invoking DESTROY methods
Feb  5 21:27:34.907 xx.xxxxx.com /usr/sbin/amavisd[4717]: child_goes_idle (child finishing)
Feb  5 21:27:34.907 xx.xxxxx.com /usr/sbin/amavisd[4717]: child_goes_idle: disconnected none (child finishing)
Feb  5 21:27:34.907 xx.xxxxx.com /usr/sbin/amavisd[4717]: SA rundown_child (0)
Feb  5 21:27:34.907 xx.xxxxx.com /usr/sbin/amavisd[4717]: SpamControl: rundown_child on SpamAssassin done
Feb  5 21:27:34.907 xx.xxxxx.com /usr/sbin/amavisd[4717]: child_finish_hook: invoking DESTROY methods
Feb  5 21:27:34.909 xx.xxxxx.com /usr/sbin/amavisd[4720]: child_goes_idle (child finishing)
Feb  5 21:27:34.909 xx.xxxxx.com /usr/sbin/amavisd[4720]: child_goes_idle: disconnected none (child finishing)
Feb  5 21:27:34.909 xx.xxxxx.com /usr/sbin/amavisd[4720]: SA rundown_child (0)
Feb  5 21:27:34.909 xx.xxxxx.com /usr/sbin/amavisd[4720]: SpamControl: rundown_child on SpamAssassin done
Feb  5 21:27:34.909 xx.xxxxx.com /usr/sbin/amavisd[4720]: child_finish_hook: invoking DESTROY methods
Feb  5 21:27:34.911 xx.xxxxx.com /usr/sbin/amavisd[4717]: Amavis::DB::SNMP DESTROY called
Feb  5 21:27:34.912 xx.xxxxx.com /usr/sbin/amavisd[4720]: Amavis::DB::SNMP DESTROY called
Feb  5 21:27:34.913 xx.xxxxx.com /usr/sbin/amavisd[4719]: Amavis::DB::SNMP DESTROY called
Feb  5 21:27:34.913 xx.xxxxx.com /usr/sbin/amavisd[4718]: Amavis::DB::SNMP DESTROY called

19

Re: iRedMail on CentOS 7

It looks fine, no errors.
Did email arrive your mailbox when you have 'amavisd debug' running?

20 (edited by lito 2015-02-06 13:30:47)

Re: iRedMail on CentOS 7

No, mail returns with this error "connect to 127.0.0.1[127.0.0.1]:10024: Connection
   refused".
I think I found potential problem. At home I have installed CentOS 7 Minimal, from original iso on virtual machine, and iredmail as well as amavisd worked fine there. I think that there are problem with CentOS 7 distributive provided by my VPS provider OVH. It's somehow was customized, because after install it looks like system is less than Minimal version, and it's was installed with 2.6 version of linux kernel, and after "yum upgrade" the kernel is not upgrading to 3.10. May be they something done with basic repos, or something like this.
And the problem, that it seems that there are no options to install custom OS on VPS.
Also I tried to upgrade distributive with # yum groupinstall "Base", and no luck sad.

21

Re: iRedMail on CentOS 7

How much memory do you have on this server?

22 (edited by lito 2015-02-06 16:18:24)

Re: iRedMail on CentOS 7

For now I have 2 VPS there with 1 and 2 Gigs of RAM. And both having the same problem.

23

Re: iRedMail on CentOS 7

Now sure whether it's caused by the customized OS on your VPS, with a normal installed OS, iRedMail should work as expected.

Is it possible to let me login to your server for further debug? I'm just curious what the problem is.

24

Re: iRedMail on CentOS 7

I just reinstalled CentOS 7 on VPS with 1 Gig of RAM, so it's clean and sent you details via PM.

25

Re: iRedMail on CentOS 7

Fixed by commenting out below line in /usr/lib/systemd/system/amavisd.service:

NoNewPrivileges=true

But amavisd works on my server with above line enabled. I don't understand this.

By the way, your VPS OS is weird. for example, package 'rsyslog' should be installed, selinux related package should be installed (other packages may check selinux related stuffs). I installed rsyslog, libselinux-utils, policycoreutils, selinux-policy on your VPS.

---------
I did a quick search with Google, someone reported this issue in Red Hat site:
https://bugzilla.redhat.com/show_bug.cgi?id=1177819

Current solution is disable this line.

--------
By the way, i don't like systemd...