Hey everyone, hopefully this can help someone who has run into this… I recently installed Kopano Core on my server and ran into an issue where I could not send any local test messages to local users. Hope keyword search helps someone else with this.
So a little troubleshooting
root@ucs2:/var/log# tail -f mail.log
Mar 22 12:43:37 ucs2 postfix/smtp[29788]: D1537106E9B: to=<user1@domain.com>, relay=none, delay=2156, delays=2156/0.03/0/0, dsn=4.4.1, status=deferred (connect to 127.0.0.1[127.0.0.1]:10024: Connection refused)
Mar 22 12:43:37 ucs2 postfix/smtp[29787]: C21591067E8: to=<user1@domain.com>, relay=none, delay=1143, delays=1143/0.02/0/0, dsn=4.4.1, status=deferred (connect to 127.0.0.1[127.0.0.1]:10024: Connection refused)Error log
root@ucs2:/var/log# tail /var/log/mail.err
Mar 20 23:15:50 ucs2 amavis[8459]: (08459-04) (!!)AV: ALL VIRUS SCANNERS FAILED
Mar 20 23:16:48 ucs2 amavis[8460]: (08460-04) (!!)AV: ALL VIRUS SCANNERS FAILED
Mar 21 03:30:14 ucs2 amavis[8459]: (08459-05) (!!)AV: ALL VIRUS SCANNERS FAILED
Mar 21 20:30:45 ucs2 amavis[8460]: (08460-05) (!!)AV: ALL VIRUS SCANNERS FAILED
Mar 22 03:31:19 ucs2 amavis[8459]: (08459-06) (!!)AV: ALL VIRUS SCANNERS FAILED
Mar 22 06:49:27 ucs2 amavis[8460]: (08460-06) (!!)AV: ALL VIRUS SCANNERS FAILED
Mar 22 08:22:40 ucs2 amavis[8459]: (08459-07) (!!)AV: ALL VIRUS SCANNERS FAILED
Mar 22 08:24:39 ucs2 amavis[8460]: (08460-07) (!!)AV: ALL VIRUS SCANNERS FAILED
Mar 22 09:17:58 ucs2 amavis[1848]: (!!)TROUBLE in pre_loop_hook: config: no rules were found! Do you need to run 'sa-update'?
Mar 22 10:08:03 ucs2 amavis[1909]: (!!)TROUBLE in pre_loop_hook: config: no rules were found! Do you need to run 'sa-update'?A little searching on the fourms pointed to the spamassasin service and amavis
root@ucs2:~# systemctl status spamassassin.service
lines 1--1...skipping...
● spamassassin.service - Perl-based spam filter using text analysis
Loaded: loaded (/lib/systemd/system/spamassassin.service; disabled; vendor preset: enabled)
Active: inactive (dead)
~
~
root@ucs2:~# systemctl restart spamassassin.service
root@ucs2:~# systemctl status spamassassin.service
● spamassassin.service - Perl-based spam filter using text analysis
Loaded: loaded (/lib/systemd/system/spamassassin.service; disabled; vendor preset: enabled)
Active: active (running) since Fri 2019-03-22 13:01:35 PDT; 5s ago
Process: 30697 ExecStart=/usr/sbin/spamd -d --pidfile=/var/run/spamd.pid $OPTIONS (code=exited, status=0/SUCCESS)
Main PID: 30699 (spamd)
Tasks: 3 (limit: 4915)
Memory: 91.6M
CPU: 2.726s
CGroup: /system.slice/spamassassin.service
├─30699 /usr/bin/perl -T -w /usr/sbin/spamd -d --pidfile=/var/run/spamd.pid --create-prefs --max-children 10 --helper-home-dir
├─30700 spamd child
└─30701 spamd child
Mar 22 13:01:31 ucs2 systemd[1]: Starting Perl-based spam filter using text analysis...
Mar 22 13:01:32 ucs2 spamd[30697]: logger: removing stderr method
Mar 22 13:01:34 ucs2 spamd[30699]: zoom: able to use 344/346 'body_0' compiled rules (99.421%)
Mar 22 13:01:35 ucs2 spamd[30699]: spamd: server started on IO::Socket::IP [::1]:783, IO::Socket::IP [127.0.0.1]:783 (running version 3.4.2)
Mar 22 13:01:35 ucs2 spamd[30699]: spamd: server pid: 30699
Mar 22 13:01:35 ucs2 systemd[1]: Started Perl-based spam filter using text analysis.
Mar 22 13:01:35 ucs2 spamd[30699]: spamd: server successfully spawned child process, pid 30700
Mar 22 13:01:35 ucs2 spamd[30699]: spamd: server successfully spawned child process, pid 30701
Mar 22 13:01:35 ucs2 spamd[30699]: prefork: child states: IS
Mar 22 13:01:35 ucs2 spamd[30699]: prefork: child states: IInow logs are different… progress.
root@ucs2:/var/log# tail -f mail.log
Mar 22 13:02:24 ucs2 postfix/error[30726]: F2A98106B01: to=<user1@domain.com>, relay=none, delay=0.19, delays=0.08/0.05/0/0.06, dsn=4.4.1, status=deferred (delivery temporarily suspended: connect to 127.0.0.1[127.0.0.1]:10024: Connection refused)
Mar 22 13:03:37 ucs2 postfix/qmgr[6034]: C21591067E8: from=<user2@domain.com>, size=1578, nrcpt=2 (queue active)
Mar 22 13:03:37 ucs2 postfix/qmgr[6034]: D4F5D1066A4: from=<user1@domain.com>, size=2906, nrcpt=1 (queue active)
Mar 22 13:03:37 ucs2 postfix/error[30742]: D4F5D1066A4: to=<user2@domain.com>, relay=none, delay=2248, delays=2248/0.04/0/0.02, dsn=4.4.1, status=deferred (delivery temporarily suspended: connect to 127.0.0.1[127.0.0.1]:10024: Connection refused)
Mar 22 13:03:37 ucs2 postfix/error[30726]: C21591067E8: to=<user2@domain.com>, relay=none, delay=2342, delays=2342/0.01/0/0.05, dsn=4.4.1, status=deferred (delivery temporarily suspended: connect to 127.0.0.1[127.0.0.1]:10024: Connection refused)
Mar 22 13:03:37 ucs2 postfix/error[30726]: C21591067E8: to=<user1@domain.com>, relay=none, delay=2342, delays=2342/0.01/0/0.06, dsn=4.4.1, status=deferred (delivery temporarily suspended: connect to 127.0.0.1[127.0.0.1]:10024: Connection refused)
root@ucs2:~# systemctl status amavis.service
● amavis.service - LSB: Starts amavisd-new mailfilter
Loaded: loaded (/etc/init.d/amavis; generated; vendor preset: enabled)
Active: active (exited) since Fri 2019-03-22 10:08:00 PDT; 2h 58min ago
Docs: man:systemd-sysv-generator(8)
Process: 849 ExecStart=/etc/init.d/amavis start (code=exited, status=0/SUCCESS)
Tasks: 0 (limit: 4915)
Memory: 0B
CPU: 0
CGroup: /system.slice/amavis.service
Mar 22 10:08:00 ucs2 amavis[1909]: No decoder for .lzo
Mar 22 10:08:00 ucs2 amavis[1909]: No decoder for .rar
Mar 22 10:08:00 ucs2 amavis[1909]: No decoder for .rpm
Mar 22 10:08:00 ucs2 amavis[1909]: No decoder for .swf
Mar 22 10:08:00 ucs2 amavis[1909]: No decoder for .zoo
Mar 22 10:08:00 ucs2 amavis[1909]: Using primary internal av scanner code for Clam Antivirus-clamd
Mar 22 10:08:00 ucs2 amavis[1909]: Deleting db files __db.002,__db.001,__db.003,snmp.db,nanny.db in /var/lib/amavis/db
Mar 22 10:08:00 ucs2 amavis[1909]: Creating db in /var/lib/amavis/db/; BerkeleyDB 0.55, libdb 5.3
Mar 22 10:08:03 ucs2 amavis[1909]: (!!)TROUBLE in pre_loop_hook: config: no rules were found! Do you need to run 'sa-update'?
Mar 22 10:08:03 ucs2 amavis[1909]: (!)_DIE: Suicide () TROUBLE in pre_loop_hook: config: no rules were found! Do you need to run 'sa-update'?
another quick form search
root@ucs2:~# /etc/cron.daily/spamassassin
root@ucs2:~# service amavis restart
root@ucs2:~# systemctl status amavis.service
● amavis.service - LSB: Starts amavisd-new mailfilter
Loaded: loaded (/etc/init.d/amavis; generated; vendor preset: enabled)
Active: active (running) since Fri 2019-03-22 13:08:14 PDT; 5s ago
Docs: man:systemd-sysv-generator(8)
Process: 31007 ExecStop=/etc/init.d/amavis stop (code=exited, status=0/SUCCESS)
Process: 31015 ExecStart=/etc/init.d/amavis start (code=exited, status=0/SUCCESS)
Tasks: 3 (limit: 4915)
Memory: 143.6M
CPU: 4.941s
CGroup: /system.slice/amavis.service
├─31032 /usr/sbin/amavisd-new (master)
├─31038 /usr/sbin/amavisd-new (virgin child)
└─31039 /usr/sbin/amavisd-new (virgin child)
Mar 22 13:08:14 ucs2 amavis[31032]: No decoder for .lrz
Mar 22 13:08:14 ucs2 amavis[31032]: No decoder for .lz4
Mar 22 13:08:14 ucs2 amavis[31032]: No decoder for .lzo
Mar 22 13:08:14 ucs2 amavis[31032]: No decoder for .rar
Mar 22 13:08:14 ucs2 amavis[31032]: No decoder for .rpm
Mar 22 13:08:14 ucs2 amavis[31032]: No decoder for .swf
Mar 22 13:08:14 ucs2 amavis[31032]: No decoder for .zoo
Mar 22 13:08:14 ucs2 amavis[31032]: Using primary internal av scanner code for Clam Antivirus-clamd
Mar 22 13:08:14 ucs2 amavis[31032]: Deleting db files __db.002,__db.001,__db.003,snmp.db,nanny.db in /var/lib/amavis/db
Mar 22 13:08:14 ucs2 amavis[31032]: Creating db in /var/lib/amavis/db/; BerkeleyDB 0.55, libdb 5.3
root@ucs2:~# postqueue -f
Mail is now flowing correctly.
I’ll try to see why it did this in the first place.