I am using the latest version 8.7.11 on CentOS 7
I have recently moved to a new server from version 8.6 and then upgraded to version 8.7.11
Since then Zimbra constantly shows some issues.
Just now the postfix service went down. See log:
/var/log/maillog
Code: Select all
Nov 18 09:57:14 mail postfix/postscreen[4603]: CONNECT from [156.216.172.25]:23176 to [88.99.136.142]:25
Nov 18 09:57:17 mail postfix/postscreen[4603]: PASS NEW [49.68.120.26]:1736
Nov 18 09:57:17 mail postfix/smtpd[16672]: connect from unknown[49.68.120.26]
Nov 18 09:57:18 mail postfix/smtpd[16672]: NOQUEUE: filter: RCPT from unknown[49.68.120.26]: <aabfre@sjepxxzf.org>: Sender address triggers FILTER smtp-amavis:[127.0.0.1]:10026; from=<aabfre@sjepxxzf.org> to=<info@abcde.de> proto=SMTP helo=<vps10586.com>
Nov 18 09:57:18 mail postfix/smtpd[16672]: NOQUEUE: filter: RCPT from unknown[49.68.120.26]: <aabfre@sjepxxzf.org>: Sender address triggers FILTER smtp-amavis:[127.0.0.1]:10024; from=<aabfre@sjepxxzf.org> to=<info@abcde.de> proto=SMTP helo=<vps10586.com>
Nov 18 09:57:18 mail postfix/smtpd[16672]: NOQUEUE: reject: RCPT from unknown[49.68.120.26]: 450 4.1.8 <aabfre@sjepxxzf.org>: Sender address rejected: Domain not found; from=<aabfre@sjepxxzf.org> to=<info@abcde.de> proto=SMTP helo=<vps10586.com>
Nov 18 09:57:18 mail postfix/smtpd[16672]: disconnect from unknown[49.68.120.26] helo=1 mail=1 rcpt=0/1 quit=1 commands=3/4
Nov 18 09:57:20 mail postfix/postscreen[4603]: PASS NEW [156.216.172.25]:23176
Nov 18 09:57:20 mail postfix/smtpd[16212]: warning: hostname host-156.216.25.172-static.tedata.net does not resolve to address 156.216.172.25
Nov 18 09:57:20 mail postfix/smtpd[16212]: connect from unknown[156.216.172.25]
Nov 18 09:57:20 mail postfix/smtpd[16212]: NOQUEUE: filter: RCPT from unknown[156.216.172.25]: <inmetaltdann@epm.net.co>: Sender address triggers FILTER smtp-amavis:[127.0.0.1]:10026; from=<inmetaltdann@epm.net.co> to=<amanda@abcde.es> proto=ESMTP helo=<host-156.216.25.172-static.tedata.net>
Nov 18 09:57:20 mail postfix/smtpd[16212]: NOQUEUE: filter: RCPT from unknown[156.216.172.25]: <inmetaltdann@epm.net.co>: Sender address triggers FILTER smtp-amavis:[127.0.0.1]:10024; from=<inmetaltdann@epm.net.co> to=<amanda@abcde.es> proto=ESMTP helo=<host-156.216.25.172-static.tedata.net>
Nov 18 09:57:20 mail postfix/smtpd[16212]: NOQUEUE: reject: RCPT from unknown[156.216.172.25]: 550 5.1.1 <amanda@abcde.es>: Recipient address rejected: abcde.es; from=<inmetaltdann@epm.net.co> to=<amanda@abcde.es> proto=ESMTP helo=<host-156.216.25.172-static.tedata.net>
Nov 18 09:57:20 mail postfix/smtpd[16212]: lost connection after DATA from unknown[156.216.172.25]
Nov 18 09:57:20 mail postfix/smtpd[16212]: disconnect from unknown[156.216.172.25] ehlo=1 mail=1 rcpt=0/1 data=0/1 commands=2/4
Nov 18 09:57:21 mail postfix/submission/smtpd[6607]: connect from static.208.20.251.148.clients.your-server.de[148.251.20.208]
Nov 18 09:57:21 mail postfix/submission/smtpd[6607]: NOQUEUE: filter: RCPT from static.208.20.251.148.clients.your-server.de[148.251.20.208]: <cloud@abcde.de>: Sender address triggers FILTER smtp-amavis:[127.0.0.1]:10026; from=<cloud@abcde.de> to=<henry.gerhardt@world-of-coffee.de> proto=ESMTP helo=<cloud.abcde.de>
Nov 18 09:57:21 mail postfix/submission/smtpd[6607]: 142631F217A1: client=static.208.20.251.148.clients.your-server.de[148.251.20.208], sasl_method=LOGIN, sasl_username=cloud@abcde.de
Nov 18 09:57:21 mail postfix/cleanup[17623]: 142631F217A1: message-id=<786d9669e95344b5a0626ad974d15b72@cloud.abcde.de>
Nov 18 09:57:21 mail postfix/qmgr[4468]: 142631F217A1: from=<cloud@abcde.de>, size=4911, nrcpt=1 (queue active)
Nov 18 09:57:21 mail postfix/submission/smtpd[6607]: disconnect from static.208.20.251.148.clients.your-server.de[148.251.20.208] ehlo=1 auth=1 mail=1 rcpt=1 data=1 quit=1 commands=6
Nov 18 09:57:21 mail postfix/dkimmilter/smtpd[13838]: connect from localhost.localdomain[127.0.0.1]
Nov 18 09:57:21 mail postfix/dkimmilter/smtpd[13838]: 36BA71F217B4: client=localhost.localdomain[127.0.0.1]
Nov 18 09:57:21 mail postfix/cleanup[17623]: 36BA71F217B4: message-id=<786d9669e95344b5a0626ad974d15b72@cloud.abcde.de>
Nov 18 09:57:21 mail postfix/qmgr[4468]: 36BA71F217B4: from=<cloud@abcde.de>, size=5631, nrcpt=1 (queue active)
Nov 18 09:57:21 mail postfix/dkimmilter/smtpd[13838]: disconnect from localhost.localdomain[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
Nov 18 09:57:21 mail postfix/smtp[16690]: 142631F217A1: to=<henry.gerhardt@world-of-coffee.de>, relay=127.0.0.1[127.0.0.1]:10026, delay=0.22, delays=0.08/0/0/0.14, dsn=2.0.0, status=sent (250 2.0.0 from MTA(smtp:[127.0.0.1]:10030): 250 2.0.0 Ok: queued as 36BA71F217B4)
Nov 18 09:57:21 mail postfix/qmgr[4468]: 142631F217A1: removed
Nov 18 09:57:21 mail postfix/amavisd/smtpd[5496]: connect from localhost.localdomain[127.0.0.1]
Nov 18 09:57:21 mail postfix/amavisd/smtpd[5496]: 6FE191F217A1: client=localhost.localdomain[127.0.0.1]
Nov 18 09:57:21 mail postfix/cleanup[17623]: 6FE191F217A1: message-id=<786d9669e95344b5a0626ad974d15b72@cloud.abcde.de>
Nov 18 09:57:21 mail postfix/amavisd/smtpd[5496]: disconnect from localhost.localdomain[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
Nov 18 09:57:21 mail postfix/qmgr[4468]: 6FE191F217A1: from=<cloud@abcde.de>, size=6022, nrcpt=1 (queue active)
Nov 18 09:57:21 mail postfix/smtp[16680]: 36BA71F217B4: to=<henry.gerhardt@world-of-coffee.de>, relay=127.0.0.1[127.0.0.1]:10032, delay=0.31, delays=0.07/0/0/0.24, dsn=2.0.0, status=sent (250 2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as 6FE191F217A1)
Nov 18 09:57:21 mail postfix/qmgr[4468]: 36BA71F217B4: removed
Nov 18 09:57:21 mail postfix/smtp[16228]: 6FE191F217A1: to=<henry.gerhardt@world-of-coffee.de>, relay=mxlb.ispgateway.de[80.67.18.126]:25, delay=0.44, delays=0.07/0/0.08/0.29, dsn=2.0.0, status=sent (250 OK id=1eFywD-0006ws-N3)
Nov 18 09:57:21 mail postfix/qmgr[4468]: 6FE191F217A1: removed
Nov 18 09:59:43 mail zmmailboxdmgr[3047]: stale pid 3530 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process
Nov 18 09:59:43 mail zmmailboxdmgr[3047]: assuming no other instance is running
Nov 18 09:59:43 mail zmmailboxdmgr[3047]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Nov 18 09:59:43 mail zmmailboxdmgr[3047]: assuming no other instance is running
Nov 18 09:59:43 mail zmmailboxdmgr[3047]: no manager process is running
Nov 18 09:59:43 mail zmmailboxdmgr[3058]: start requested
Nov 18 09:59:43 mail zmmailboxdmgr[3058]: checking if another instance of manager is already running
Nov 18 09:59:43 mail zmmailboxdmgr[3058]: stale pid 3530 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process
Nov 18 09:59:43 mail zmmailboxdmgr[3058]: assuming no other instance is running
Nov 18 09:59:43 mail zmmailboxdmgr[3058]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Nov 18 09:59:43 mail zmmailboxdmgr[3058]: assuming no other instance is running
Nov 18 09:59:43 mail zmmailboxdmgr[3059]: wrote manager pid 3059 to /opt/zimbra/log/zmmailboxd_manager.pid
Nov 18 09:59:43 mail zmmailboxdmgr[3059]: manager started mailboxd/JVM with pid 3060
Nov 18 09:59:43 mail zmmailboxdmgr[3060]: wrote java pid 3060 to /opt/zimbra/log/zmmailboxd_java.pid
Nov 18 09:59:50 mail amavis-mc[3436]: amavis master process starting. daemonized as PID [3436], perl 5.016003
Nov 18 09:59:50 mail amavis-mc[3436]: Process [3440] started: /opt/zimbra/common/sbin/amavis-services msg-forwarder
Nov 18 09:59:50 mail amavis-mc[3436]: Process [3441] started: /opt/zimbra/common/sbin/amavis-services childproc-minder
Nov 18 09:59:50 mail amavis-mc[3436]: Process [3442] started: /opt/zimbra/common/sbin/amavis-services snmp-responder
Nov 18 09:59:50 mail amavis-services[3440]: amavis-services-2.9.0 (20140506) task 'msg-forwarder' [3440] started. ZMQ::LibZMQ3 1.19, lib 4.1.4
Nov 18 09:59:50 mail amavis-services[3441]: amavis-services-2.9.0 (20140506) task 'childproc-minder' [3441] started. ZMQ::LibZMQ3 1.19, lib 4.1.4
Nov 18 09:59:50 mail amavis-services[3442]: amavis-services-2.9.0 (20140506) task 'snmp-responder' [3442] started. ZMQ::LibZMQ3 1.19, lib 4.1.4
Nov 18 09:59:51 mail amavis-services[3441]: childproc_minder: FLUSH process states
Nov 18 09:59:51 mail amavis-services[3442]: snmp_responder: FLUSH snmp data
Nov 18 09:59:51 mail amavis-services[3441]: childproc_minder: FLUSH process states
Nov 18 10:00:01 mail postfix/postqueue[3792]: fatal: Queue report unavailable - mail system is down
Nov 18 10:10:01 mail postfix/postqueue[11394]: fatal: Queue report unavailable - mail system is down
Nov 18 10:20:01 mail postfix/postqueue[18170]: fatal: Queue report unavailable - mail system is down
Nov 18 10:30:01 mail postfix/postqueue[25005]: fatal: Queue report unavailable - mail system is down
Nov 18 10:40:01 mail postfix/postqueue[32031]: fatal: Queue report unavailable - mail system is down
Nov 18 10:50:01 mail postfix/postqueue[6492]: fatal: Queue report unavailable - mail system is down
Nov 18 11:00:01 mail postfix/postqueue[13469]: fatal: Queue report unavailable - mail system is down
Nov 18 11:10:01 mail postfix/postqueue[20513]: fatal: Queue report unavailable - mail system is down
Code: Select all
Nov 18 09:57:20 mail postfix/postscreen[4603]: PASS NEW [156.216.172.25]:23176
Nov 18 09:57:20 mail postfix/smtpd[16212]: warning: hostname host-156.216.25.172-static.tedata.net does not resolve to address 156.216.172.25
Nov 18 09:57:20 mail postfix/smtpd[16212]: connect from unknown[156.216.172.25]
Nov 18 09:57:20 mail postfix/smtpd[16212]: NOQUEUE: filter: RCPT from unknown[156.216.172.25]: <inmetaltdann@epm.net.co>: Sender address triggers FILTER smtp-amavis:[127.0.0.1]:10026; from=<inmetaltdann@epm.net.co> to=<amanda@abcde.es> proto=ESMTP helo=<host-156.216.25.172-static.tedata.net>
Nov 18 09:57:20 mail postfix/smtpd[16212]: NOQUEUE: filter: RCPT from unknown[156.216.172.25]: <inmetaltdann@epm.net.co>: Sender address triggers FILTER smtp-amavis:[127.0.0.1]:10024; from=<inmetaltdann@epm.net.co> to=<amanda@abcde.es> proto=ESMTP helo=<host-156.216.25.172-static.tedata.net>
Nov 18 09:57:20 mail postfix/smtpd[16212]: NOQUEUE: reject: RCPT from unknown[156.216.172.25]: 550 5.1.1 <amanda@abcde.es>: Recipient address rejected: abcde.es; from=<inmetaltdann@epm.net.co> to=<amanda@abcde.es> proto=ESMTP helo=<host-156.216.25.172-static.tedata.net>
Nov 18 09:57:20 mail postfix/smtpd[16212]: lost connection after DATA from unknown[156.216.172.25]
Nov 18 09:57:20 mail postfix/smtpd[16212]: disconnect from unknown[156.216.172.25] ehlo=1 mail=1 rcpt=0/1 data=0/1 commands=2/4
Nov 18 09:57:21 mail postfix/submission/smtpd[6607]: connect from static.208.20.251.148.clients.your-server.de[148.251.20.208]
Nov 18 09:57:21 mail saslauthd[4282]: zmauth: authenticating against elected url 'https://mail.abcde.com:7073/service/admin/soap/' ...
Nov 18 09:57:21 mail saslauthd[4282]: zmpost: url='https://mail.abcde.com:7073/service/admin/soap/' returned buffer->data='<soap:Envelope xmlns:soap="http://www.w3.org/2003/05/soap-envelope"><soap:Header><context xmlns="urn:zimbra"><change token="51527"/></context></soap:Header><soap:Body><AuthResponse xmlns="urn:zimbraAccount"><authToken>0_e73c5a59263b0f05e9e819001c9ad5c529609ae1_69643d33363a37336437386334302d636634312d346136622d396432392d6562323834616534353836393b6578703d31333a313531313136383234313038303b747970653d363a7a696d6272613b753d313a613b7469643d393a3431303236393835343b76657273696f6e3d31343a382e372e31315f47415f313835343b</authToken><lifetime>172800000</lifetime><skin>harmony</skin></AuthResponse></soap:Body></soap:Envelope>', hti->error=''
Nov 18 09:57:21 mail saslauthd[4282]: auth_zimbra: cloud@abcde.de auth OK
Nov 18 09:57:21 mail postfix/submission/smtpd[6607]: NOQUEUE: filter: RCPT from static.208.20.251.148.clients.your-server.de[148.251.20.208]: <cloud@abcde.de>: Sender address triggers FILTER smtp-amavis:[127.0.0.1]:10026; from=<cloud@abcde.de> to=<henry.gerhardt@world-of-coffee.de> proto=ESMTP helo=<cloud.abcde.de>
Nov 18 09:57:21 mail postfix/submission/smtpd[6607]: 142631F217A1: client=static.208.20.251.148.clients.your-server.de[148.251.20.208], sasl_method=LOGIN, sasl_username=cloud@abcde.de
Nov 18 09:57:21 mail postfix/cleanup[17623]: 142631F217A1: message-id=<786d9669e95344b5a0626ad974d15b72@cloud.abcde.de>
Nov 18 09:57:21 mail postfix/qmgr[4468]: 142631F217A1: from=<cloud@abcde.de>, size=4911, nrcpt=1 (queue active)
Nov 18 09:57:21 mail postfix/submission/smtpd[6607]: disconnect from static.208.20.251.148.clients.your-server.de[148.251.20.208] ehlo=1 auth=1 mail=1 rcpt=1 data=1 quit=1 commands=6
Nov 18 09:57:21 mail amavis[10929]: (10929-09) ESMTP [127.0.0.1]:10026 /opt/zimbra/data/amavisd/tmp/amavis-20171118T094846-10929-6tObIhSO: <cloud@abcde.de> -> <henry.gerhardt@world-of-coffee.de> Received: from mail.abcde.com ([127.0.0.1]) by localhost (mail.abcde.com [127.0.0.1]) (amavisd-new, port 10026) with ESMTP for <henry.gerhardt@world-of-coffee.de>; Sat, 18 Nov 2017 09:57:21 +0100 (CET)
Nov 18 09:57:21 mail amavis[10929]: (10929-09) Checking: W1mDe31zwY1P ORIGINATING [148.251.20.208] <cloud@abcde.de> -> <henry.gerhardt@world-of-coffee.de>
Nov 18 09:57:21 mail postfix/dkimmilter/smtpd[13838]: connect from localhost.localdomain[127.0.0.1]
Nov 18 09:57:21 mail postfix/dkimmilter/smtpd[13838]: 36BA71F217B4: client=localhost.localdomain[127.0.0.1]
Nov 18 09:57:21 mail postfix/cleanup[17623]: 36BA71F217B4: message-id=<786d9669e95344b5a0626ad974d15b72@cloud.abcde.de>
Nov 18 09:57:21 mail opendkim[4235]: 36BA71F217B4: no signing table match for 'cloud@abcde.de'
Nov 18 09:57:21 mail postfix/qmgr[4468]: 36BA71F217B4: from=<cloud@abcde.de>, size=5631, nrcpt=1 (queue active)
Nov 18 09:57:21 mail postfix/dkimmilter/smtpd[13838]: disconnect from localhost.localdomain[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
Nov 18 09:57:21 mail amavis[10929]: (10929-09) W1mDe31zwY1P FWD from <cloud@abcde.de> -> <henry.gerhardt@world-of-coffee.de>, BODY=7BIT 250 2.0.0 from MTA(smtp:[127.0.0.1]:10030): 250 2.0.0 Ok: queued as 36BA71F217B4
Nov 18 09:57:21 mail amavis[10929]: (10929-09) Passed CLEAN {RelayedOutbound}, ORIGINATING LOCAL [148.251.20.208]:54771 [148.251.20.208] <cloud@abcde.de> -> <henry.gerhardt@world-of-coffee.de>, Queue-ID: 142631F217A1, Message-ID: <786d9669e95344b5a0626ad974d15b72@cloud.abcde.de>, mail_id: W1mDe31zwY1P, Hits: -, size: 5117, queued_as: 36BA71F217B4, 142 ms
Nov 18 09:57:21 mail amavis[11303]: (11303-14) ESMTP [127.0.0.1]:10032 /opt/zimbra/data/amavisd/tmp/amavis-20171118T094834-11303-nvO19bvb: <cloud@abcde.de> -> <henry.gerhardt@world-of-coffee.de> SIZE=5631 Received: from mail.abcde.com ([127.0.0.1]) by localhost (mail.abcde.com [127.0.0.1]) (amavisd-new, port 10032) with ESMTP for <henry.gerhardt@world-of-coffee.de>; Sat, 18 Nov 2017 09:57:21 +0100 (CET)
Nov 18 09:57:21 mail postfix/smtp[16690]: 142631F217A1: to=<henry.gerhardt@world-of-coffee.de>, relay=127.0.0.1[127.0.0.1]:10026, delay=0.22, delays=0.08/0/0/0.14, dsn=2.0.0, status=sent (250 2.0.0 from MTA(smtp:[127.0.0.1]:10030): 250 2.0.0 Ok: queued as 36BA71F217B4)
Nov 18 09:57:21 mail postfix/qmgr[4468]: 142631F217A1: removed
Nov 18 09:57:21 mail amavis[11303]: (11303-14) Checking: yFC6PmoiibE1 ORIGINATING_POST/MYNETS [127.0.0.1] <cloud@abcde.de> -> <henry.gerhardt@world-of-coffee.de>
Nov 18 09:57:21 mail postfix/amavisd/smtpd[5496]: connect from localhost.localdomain[127.0.0.1]
Nov 18 09:57:21 mail postfix/amavisd/smtpd[5496]: 6FE191F217A1: client=localhost.localdomain[127.0.0.1]
Nov 18 09:57:21 mail postfix/cleanup[17623]: 6FE191F217A1: message-id=<786d9669e95344b5a0626ad974d15b72@cloud.abcde.de>
Nov 18 09:57:21 mail postfix/amavisd/smtpd[5496]: disconnect from localhost.localdomain[127.0.0.1] ehlo=1 mail=1 rcpt=1 data=1 quit=1 commands=5
Nov 18 09:57:21 mail postfix/qmgr[4468]: 6FE191F217A1: from=<cloud@abcde.de>, size=6022, nrcpt=1 (queue active)
Nov 18 09:57:21 mail amavis[11303]: (11303-14) yFC6PmoiibE1 FWD from <cloud@abcde.de> -> <henry.gerhardt@world-of-coffee.de>, BODY=7BIT 250 2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as 6FE191F217A1
Nov 18 09:57:21 mail amavis[11303]: (11303-14) Passed CLEAN {RelayedOutbound}, ORIGINATING_POST/MYNETS LOCAL [127.0.0.1]:33672 [148.251.20.208] <cloud@abcde.de> -> <henry.gerhardt@world-of-coffee.de>, Queue-ID: 36BA71F217B4, Message-ID: <786d9669e95344b5a0626ad974d15b72@cloud.abcde.de>, mail_id: yFC6PmoiibE1, Hits: -0.404, size: 5594, queued_as: 6FE191F217A1, 238 ms
Nov 18 09:57:21 mail postfix/smtp[16680]: 36BA71F217B4: to=<henry.gerhardt@world-of-coffee.de>, relay=127.0.0.1[127.0.0.1]:10032, delay=0.31, delays=0.07/0/0/0.24, dsn=2.0.0, status=sent (250 2.0.0 from MTA(smtp:[127.0.0.1]:10025): 250 2.0.0 Ok: queued as 6FE191F217A1)
Nov 18 09:57:21 mail postfix/qmgr[4468]: 36BA71F217B4: removed
Nov 18 09:57:21 mail postfix/smtp[16228]: 6FE191F217A1: to=<henry.gerhardt@world-of-coffee.de>, relay=mxlb.ispgateway.de[80.67.18.126]:25, delay=0.44, delays=0.07/0/0.08/0.29, dsn=2.0.0, status=sent (250 OK id=1eFywD-0006ws-N3)
Nov 18 09:57:21 mail postfix/qmgr[4468]: 6FE191F217A1: removed
Nov 18 09:58:46 mail systemd-logind: Watching system buttons on /dev/input/event1 (Power Button)
Nov 18 09:58:46 mail systemd-logind: Watching system buttons on /dev/input/event2 (Fujitsu FUJ02E3)
Nov 18 09:58:46 mail systemd-logind: Watching system buttons on /dev/input/event0 (Power Button)
Nov 18 09:58:46 mail systemd-logind: New seat seat0.
Nov 18 09:58:51 mail dbus[754]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.2" (uid=0 pid=764 comm="/usr/bin/python -Es /usr/sbin/firewalld --nofork -") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.ServiceUnknown" requested_reply="0" destination=":1.9" (uid=0 pid=1028 comm="/usr/bin/python -Es /usr/bin/firewall-cmd --zone=p")
Nov 18 09:58:51 mail dbus[754]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.2" (uid=0 pid=764 comm="/usr/bin/python -Es /usr/sbin/firewalld --nofork -") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.ServiceUnknown" requested_reply="0" destination=":1.9" (uid=0 pid=1028 comm="/usr/bin/python -Es /usr/bin/firewall-cmd --zone=p")
Nov 18 09:58:51 mail dbus[754]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.2" (uid=0 pid=764 comm="/usr/bin/python -Es /usr/sbin/firewalld --nofork -") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.ServiceUnknown" requested_reply="0" destination=":1.9" (uid=0 pid=1028 comm="/usr/bin/python -Es /usr/bin/firewall-cmd --zone=p")
Nov 18 09:58:51 mail dbus[754]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.2" (uid=0 pid=764 comm="/usr/bin/python -Es /usr/sbin/firewalld --nofork -") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.ServiceUnknown" requested_reply="0" destination=":1.9" (uid=0 pid=1028 comm="/usr/bin/python -Es /usr/bin/firewall-cmd --zone=p")
Nov 18 09:58:51 mail dbus[754]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.2" (uid=0 pid=764 comm="/usr/bin/python -Es /usr/sbin/firewalld --nofork -") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.ServiceUnknown" requested_reply="0" destination=":1.9" (uid=0 pid=1028 comm="/usr/bin/python -Es /usr/bin/firewall-cmd --zone=p")
Nov 18 09:58:51 mail dbus[754]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.2" (uid=0 pid=764 comm="/usr/bin/python -Es /usr/sbin/firewalld --nofork -") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.ServiceUnknown" requested_reply="0" destination=":1.9" (uid=0 pid=1028 comm="/usr/bin/python -Es /usr/bin/firewall-cmd --zone=p")
Nov 18 09:58:53 mail dbus[754]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.2" (uid=0 pid=764 comm="/usr/bin/python -Es /usr/sbin/firewalld --nofork -") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.ServiceUnknown" requested_reply="0" destination=":1.12" (uid=0 pid=1108 comm="/usr/bin/python -Es /usr/bin/firewall-cmd --zone=p")
Nov 18 09:58:53 mail dbus[754]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.2" (uid=0 pid=764 comm="/usr/bin/python -Es /usr/sbin/firewalld --nofork -") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.ServiceUnknown" requested_reply="0" destination=":1.12" (uid=0 pid=1108 comm="/usr/bin/python -Es /usr/bin/firewall-cmd --zone=p")
Nov 18 09:58:53 mail dbus[754]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.2" (uid=0 pid=764 comm="/usr/bin/python -Es /usr/sbin/firewalld --nofork -") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.ServiceUnknown" requested_reply="0" destination=":1.12" (uid=0 pid=1108 comm="/usr/bin/python -Es /usr/bin/firewall-cmd --zone=p")
Nov 18 09:58:53 mail dbus[754]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.2" (uid=0 pid=764 comm="/usr/bin/python -Es /usr/sbin/firewalld --nofork -") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.ServiceUnknown" requested_reply="0" destination=":1.12" (uid=0 pid=1108 comm="/usr/bin/python -Es /usr/bin/firewall-cmd --zone=p")
Nov 18 09:58:53 mail dbus[754]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.2" (uid=0 pid=764 comm="/usr/bin/python -Es /usr/sbin/firewalld --nofork -") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.ServiceUnknown" requested_reply="0" destination=":1.12" (uid=0 pid=1108 comm="/usr/bin/python -Es /usr/bin/firewall-cmd --zone=p")
Nov 18 09:58:53 mail dbus[754]: [system] Rejected send message, 0 matched rules; type="error", sender=":1.2" (uid=0 pid=764 comm="/usr/bin/python -Es /usr/sbin/firewalld --nofork -") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.ServiceUnknown" requested_reply="0" destination=":1.12" (uid=0 pid=1108 comm="/usr/bin/python -Es /usr/bin/firewall-cmd --zone=p")
Nov 18 09:58:54 mail su: (to zimbra) root on none
Nov 18 09:59:00 mail zimbramon[1341]: 1341:info: Starting services initiated by zmcontrol
Nov 18 09:59:00 mail slapd[1405]: @(#) $OpenLDAP: slapd 2.4.44 (Sep 29 2016 14:52:27) $#012#011build@c787:/home/build/git/87/packages/thirdparty/openldap/build/RHEL7_64/zimbra-openldap/rpm/BUILD/openldap-2.4.44/servers/slapd
Nov 18 09:59:01 mail slapd[1406]: slapd starting
Nov 18 09:59:06 mail zimbramon[1341]: 1341:info: Starting zmconfigd via zmcontrol
Nov 18 09:59:09 mail zmconfigd[1431]: zmconfigd started on mail.abcde.com with loglevel=3 pid=1431
Nov 18 09:59:09 mail zmconfigd[1431]: Fetching All configs
Nov 18 09:59:10 mail zmconfigd[1431]: All configs fetched in 0.24 seconds
Nov 18 09:59:10 mail zmconfigd[1431]: Rewrote: /opt/zimbra/conf/freshclam.conf with mode 600 (0.02 sec)
Nov 18 09:59:10 mail zmconfigd[1431]: Rewrote: /opt/zimbra/conf/opendkim.conf with mode 440 (0.01 sec)
Nov 18 09:59:10 mail zmconfigd[1431]: Rewrote: /opt/zimbra/mailboxd/webapps/service/WEB-INF/web.xml with mode 440 (0.03 sec)
Nov 18 09:59:10 mail zmconfigd[1431]: Rewrote: /opt/zimbra/mailboxd/webapps/zimbraAdmin/WEB-INF/jetty-env.xml with mode 440 (0.00 sec)
Nov 18 09:59:10 mail zmconfigd[1431]: Rewrote: /opt/zimbra/conf/dspam.conf with mode 440 (0.01 sec)
Nov 18 09:59:10 mail zmconfigd[1431]: Rewrote: /opt/zimbra/mailboxd/webapps/zimbra/WEB-INF/jetty-env.xml with mode 440 (0.00 sec)
Nov 18 09:59:10 mail zmconfigd[1431]: Rewrote: /opt/zimbra/conf/clamd.conf with mode 440 (0.01 sec)
Nov 18 09:59:10 mail zmconfigd[1431]: Rewrote: /opt/zimbra/mailboxd/webapps/zimbraAdmin/WEB-INF/web.xml with mode 440 (0.02 sec)
Nov 18 09:59:10 mail zmconfigd[1431]: Rewrote: /opt/zimbra/mailboxd/etc/spnego.conf with mode 440 (0.00 sec)
Nov 18 09:59:10 mail zmconfigd[1431]: Rewrote: /opt/zimbra/common/conf/tag_as_originating.re with mode 440 (0.00 sec)
Nov 18 09:59:10 mail zmconfigd[1431]: Rewrote: /opt/zimbra/conf/unbound.conf with mode 440 (0.00 sec)
Nov 18 09:59:10 mail zmconfigd[1431]: Rewrote: /opt/zimbra/mailboxd/webapps/zimbra/WEB-INF/web.xml with mode 440 (0.01 sec)
Nov 18 09:59:10 mail zmconfigd[1431]: Rewrote: /opt/zimbra/mailboxd/etc/jetty.xml with mode 440 (0.03 sec)
Nov 18 09:59:10 mail zmconfigd[1431]: Rewrote: /opt/zimbra/conf/spnego_java_options with mode 440 (0.00 sec)
Nov 18 09:59:10 mail zmconfigd[1431]: Rewrote: /opt/zimbra/conf/sasl2/smtpd.conf with mode 440 (0.00 sec)
Nov 18 09:59:10 mail zmconfigd[1431]: Rewrote: /opt/zimbra/mailboxd/etc/spnego.properties with mode 440 (0.00 sec)
Nov 18 09:59:10 mail zmconfigd[1431]: Rewrote: /opt/zimbra/conf/postfix_header_checks with mode 440 (0.00 sec)
Nov 18 09:59:10 mail zmconfigd[1431]: Rewrote: /opt/zimbra/conf/opendkim-localnets.conf with mode 440 (0.00 sec)
Nov 18 09:59:10 mail zmconfigd[1431]: Rewrote: /opt/zimbra/mailboxd/etc/krb5.ini with mode 440 (0.00 sec)
Nov 18 09:59:10 mail zmconfigd[1431]: Rewrote: /opt/zimbra/conf/stats.conf with mode 440 (0.00 sec)
Nov 18 09:59:10 mail zmconfigd[1431]: Rewrote: /opt/zimbra/common/conf/tag_as_foreign.re with mode 440 (0.00 sec)
Nov 18 09:59:10 mail zmconfigd[1431]: Rewrote: /opt/zimbra/conf/saslauthd.conf with mode 440 (0.00 sec)
Nov 18 09:59:10 mail zmconfigd[1431]: Rewrote: /opt/zimbra/conf/log4j.properties with mode 440 (0.01 sec)
Nov 18 09:59:10 mail zmconfigd[1431]: Rewrote: /opt/zimbra/conf/amavisd.conf with mode 440 (0.03 sec)
Nov 18 09:59:10 mail zmconfigd[1431]: Rewrote: /opt/zimbra/common/conf/master.cf with mode 440 (0.01 sec)
Nov 18 09:59:10 mail zmconfigd[1431]: Rewrote: /opt/zimbra/mailboxd/webapps/zimlet/WEB-INF/web.xml with mode 440 (0.00 sec)
Nov 18 09:59:10 mail zmconfigd[1431]: Rewrote: /opt/zimbra/mailboxd/modules/setuid.mod with mode 440 (0.00 sec)
Nov 18 09:59:10 mail zmconfigd[1431]: Rewrote: /opt/zimbra/data/spamassassin/localrules/salocal.cf with mode 440 (0.00 sec)
Nov 18 09:59:10 mail zmconfigd[1431]: Rewrote: /opt/zimbra/conf/mta_milter_options with mode 440 (0.01 sec)
Nov 18 09:59:10 mail zmconfigd[1431]: Rewrote: /opt/zimbra/mailboxd/start.d/setuid.ini with mode 440 (0.00 sec)
Nov 18 09:59:20 mail zmconfigd[1431]: All rewrite threads completed in 10.30 sec
Nov 18 09:59:20 mail zmconfigd[1431]: All restarts completed in 0.00 sec
Nov 18 09:59:21 mail zimbramon[1341]: 1341:info: Starting dnscache via zmcontrol
Nov 18 09:59:21 mail unbound: [2543:0] warning: did not exit gracefully last time (2539)
Nov 18 09:59:21 mail unbound: [2544:0] notice: init module 0: validator
Nov 18 09:59:21 mail unbound: [2544:0] notice: init module 1: iterator
Nov 18 09:59:21 mail unbound: [2544:0] info: start of service (unbound 1.5.9).
Nov 18 09:59:23 mail zimbramon[1341]: 1341:info: Starting logger via zmcontrol
Nov 18 09:59:25 mail zimbramon[1341]: 1341:info: Starting mailbox via zmcontrol
Nov 18 09:59:43 mail zmmailboxdmgr[3047]: stale pid 3530 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process
Nov 18 09:59:43 mail zmmailboxdmgr[3047]: assuming no other instance is running
Nov 18 09:59:43 mail zmmailboxdmgr[3047]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Nov 18 09:59:43 mail zmmailboxdmgr[3047]: assuming no other instance is running
Nov 18 09:59:43 mail zmmailboxdmgr[3047]: no manager process is running
Nov 18 09:59:43 mail zmmailboxdmgr[3058]: start requested
Nov 18 09:59:43 mail zmmailboxdmgr[3058]: checking if another instance of manager is already running
Nov 18 09:59:43 mail zmmailboxdmgr[3058]: stale pid 3530 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process
Nov 18 09:59:43 mail zmmailboxdmgr[3058]: assuming no other instance is running
Nov 18 09:59:43 mail zmmailboxdmgr[3058]: file /opt/zimbra/log/zmmailboxd.pid does not exist
Nov 18 09:59:43 mail zmmailboxdmgr[3058]: assuming no other instance is running
Nov 18 09:59:43 mail zmmailboxdmgr[3059]: wrote manager pid 3059 to /opt/zimbra/log/zmmailboxd_manager.pid
Nov 18 09:59:43 mail zmmailboxdmgr[3059]: manager started mailboxd/JVM with pid 3060
Nov 18 09:59:43 mail zmmailboxdmgr[3060]: wrote java pid 3060 to /opt/zimbra/log/zmmailboxd_java.pid
Nov 18 09:59:45 mail zimbramon[1341]: 1341:info: Starting memcached via zmcontrol
Nov 18 09:59:49 mail zimbramon[1341]: 1341:info: Starting proxy via zmcontrol
Nov 18 09:59:50 mail zimbramon[1341]: 1341:info: Starting amavis via zmcontrol
Nov 18 09:59:50 mail amavis-mc[3436]: amavis master process starting. daemonized as PID [3436], perl 5.016003
Nov 18 09:59:50 mail amavis-mc[3436]: Process [3440] started: /opt/zimbra/common/sbin/amavis-services msg-forwarder
Nov 18 09:59:50 mail amavis-mc[3436]: Process [3441] started: /opt/zimbra/common/sbin/amavis-services childproc-minder
Nov 18 09:59:50 mail amavis-mc[3436]: Process [3442] started: /opt/zimbra/common/sbin/amavis-services snmp-responder
Nov 18 09:59:50 mail amavis-services[3440]: amavis-services-2.9.0 (20140506) task 'msg-forwarder' [3440] started. ZMQ::LibZMQ3 1.19, lib 4.1.4
Nov 18 09:59:50 mail amavis-services[3441]: amavis-services-2.9.0 (20140506) task 'childproc-minder' [3441] started. ZMQ::LibZMQ3 1.19, lib 4.1.4
Nov 18 09:59:50 mail amavis-services[3442]: amavis-services-2.9.0 (20140506) task 'snmp-responder' [3442] started. ZMQ::LibZMQ3 1.19, lib 4.1.4
Nov 18 09:59:51 mail amavis-services[3441]: childproc_minder: FLUSH process states
Nov 18 09:59:51 mail amavis-services[3442]: snmp_responder: FLUSH snmp data
Nov 18 09:59:51 mail amavis-services[3441]: childproc_minder: FLUSH process states
Nov 18 09:59:51 mail amavis[3463]: Ignoring stale PID file /opt/zimbra/log/amavisd.pid, older than system uptime 0 0:01:00
Nov 18 09:59:51 mail amavis[3463]: starting. /opt/zimbra/common/sbin/amavisd at mail.abcde.com amavisd-new-2.10.1 (20141025), Unicode aware, LC_ALL="C", LANG="C"
Nov 18 09:59:51 mail amavis[3463]: perl=5.016003, user=997, EUID: 997 (997); group=, EGID: 995 4 5 89 995 (995 4 5 89 995)
Nov 18 09:59:52 mail amavis[3463]: SpamControl: init_pre_chroot on SpamAssassin done
Nov 18 09:59:52 mail amavis[3487]: Net::Server: Process Backgrounded
Nov 18 09:59:52 mail amavis[3487]: Net::Server: 2017/11/18-09:59:52 Amavis (type Net::Server::PreForkSimple) starting! pid(3487)
Nov 18 09:59:52 mail amavis[3487]: Net::Server: Binding to UNIX socket file "/opt/zimbra/data/amavisd/amavisd.sock"
Nov 18 09:59:52 mail amavis[3487]: Net::Server: Binding to TCP port 10024 on host 127.0.0.1 with IPv4
Nov 18 09:59:52 mail amavis[3487]: Net::Server: Binding to TCP port 10024 on host ::1 with IPv6
Nov 18 09:59:52 mail amavis[3487]: Net::Server: Binding to TCP port 10026 on host 127.0.0.1 with IPv4
Nov 18 09:59:52 mail amavis[3487]: Net::Server: Binding to TCP port 10026 on host ::1 with IPv6
Nov 18 09:59:52 mail amavis[3487]: Net::Server: Binding to TCP port 10032 on host 127.0.0.1 with IPv4
Nov 18 09:59:52 mail amavis[3487]: Net::Server: Binding to TCP port 10032 on host ::1 with IPv6
Nov 18 09:59:52 mail amavis[3487]: Net::Server: Group Not Defined. Defaulting to EGID '995 4 5 89 995'
Nov 18 09:59:52 mail amavis[3487]: Net::Server: User Not Defined. Defaulting to EUID '997'
Nov 18 09:59:52 mail amavis[3487]: Module Amavis::Conf 2.404
Nov 18 09:59:52 mail amavis[3487]: Module Archive::Zip 1.53
Nov 18 09:59:52 mail amavis[3487]: Module Compress::Raw::Zlib 2.069
Nov 18 09:59:52 mail amavis[3487]: Module Compress::Zlib 2.069
Nov 18 09:59:52 mail amavis[3487]: Module Crypt::OpenSSL::RSA 0.28
Nov 18 09:59:52 mail amavis[3487]: Module DB_File 1.835
Nov 18 09:59:52 mail amavis[3487]: Module Digest::MD5 2.52
Nov 18 09:59:52 mail amavis[3487]: Module Digest::SHA 5.85
Nov 18 09:59:52 mail amavis[3487]: Module Encode 2.51
Nov 18 09:59:52 mail amavis[3487]: Module File::LibMagic 1.15
Nov 18 09:59:52 mail amavis[3487]: Module File::Temp 0.2301
Nov 18 09:59:52 mail amavis[3487]: Module IO::Socket::INET6 2.72
Nov 18 09:59:52 mail amavis[3487]: Module IO::Socket::IP 0.37
Nov 18 09:59:52 mail amavis[3487]: Module MIME::Entity 5.507
Nov 18 09:59:52 mail amavis[3487]: Module MIME::Parser 5.507
Nov 18 09:59:52 mail amavis[3487]: Module MIME::Tools 5.507
Nov 18 09:59:52 mail amavis[3487]: Module Mail::DKIM::Signer 0.4
Nov 18 09:59:52 mail amavis[3487]: Module Mail::DKIM::Verifier 0.4
Nov 18 09:59:52 mail amavis[3487]: Module Mail::Header 2.14
Nov 18 09:59:52 mail amavis[3487]: Module Mail::Internet 2.14
Nov 18 09:59:52 mail amavis[3487]: Module Mail::SPF v2.009
Nov 18 09:59:52 mail amavis[3487]: Module Mail::SpamAssassin 3.004001
Nov 18 09:59:52 mail amavis[3487]: Module Net::DNS 1.04
Nov 18 09:59:52 mail amavis[3487]: Module Net::LDAP 0.65
Nov 18 09:59:52 mail amavis[3487]: Module Net::LibIDN 0.12
Nov 18 09:59:52 mail amavis[3487]: Module Net::Server 2.008
Nov 18 09:59:52 mail amavis[3487]: Module NetAddr::IP 4.078
Nov 18 09:59:52 mail amavis[3487]: Module Scalar::Util 1.27
Nov 18 09:59:52 mail amavis[3487]: Module Socket 2.020
Nov 18 09:59:52 mail amavis[3487]: Module Socket6 0.23
Nov 18 09:59:52 mail amavis[3487]: Module Time::HiRes 1.9725
Nov 18 09:59:52 mail amavis[3487]: Module URI 1.69
Nov 18 09:59:52 mail amavis[3487]: Module Unix::Getrusage 0.03
Nov 18 09:59:52 mail amavis[3487]: Module Unix::Syslog 1.1
Nov 18 09:59:52 mail amavis[3487]: Module ZMQ::LibZMQ3 1.19
Nov 18 09:59:52 mail amavis[3487]: Amavis::ZMQ code loaded
Nov 18 09:59:52 mail amavis[3487]: Amavis::DB code NOT loaded
Nov 18 09:59:52 mail amavis[3487]: SQL base code NOT loaded
Nov 18 09:59:52 mail amavis[3487]: SQL::Log code NOT loaded
Nov 18 09:59:52 mail amavis[3487]: SQL::Quarantine NOT loaded
Nov 18 09:59:52 mail amavis[3487]: Lookup::SQL code NOT loaded
Nov 18 09:59:52 mail amavis[3487]: Lookup::LDAP code loaded
Nov 18 09:59:52 mail amavis[3487]: AM.PDP-in proto code loaded
Nov 18 09:59:52 mail amavis[3487]: SMTP-in proto code loaded
Nov 18 09:59:52 mail amavis[3487]: Courier proto code NOT loaded
Nov 18 09:59:52 mail amavis[3487]: SMTP-out proto code loaded
Nov 18 09:59:52 mail amavis[3487]: Pipe-out proto code NOT loaded
Nov 18 09:59:52 mail amavis[3487]: BSMTP-out proto code NOT loaded
Nov 18 09:59:52 mail amavis[3487]: Local-out proto code loaded
Nov 18 09:59:52 mail amavis[3487]: OS_Fingerprint code NOT loaded
Nov 18 09:59:52 mail amavis[3487]: ANTI-VIRUS code loaded
Nov 18 09:59:52 mail amavis[3487]: ANTI-SPAM code loaded
Nov 18 09:59:52 mail amavis[3487]: ANTI-SPAM-EXT code NOT loaded
Nov 18 09:59:52 mail amavis[3487]: ANTI-SPAM-C code NOT loaded
Nov 18 09:59:52 mail amavis[3487]: ANTI-SPAM-SA code loaded
Nov 18 09:59:52 mail amavis[3487]: Unpackers code loaded
Nov 18 09:59:52 mail amavis[3487]: DKIM code loaded
Nov 18 09:59:52 mail amavis[3487]: Tools code NOT loaded
Nov 18 09:59:52 mail amavis[3487]: Found $file at /usr/bin/file
Nov 18 09:59:52 mail amavis[3487]: No $altermime, not using it
Nov 18 09:59:52 mail amavis[3487]: Internal decoder for .mail
Nov 18 09:59:52 mail amavis[3487]: No ext program for .F, tried: unfreeze, freeze -d, melt, fcat
Nov 18 09:59:52 mail amavis[3487]: Found decoder for .Z at /usr/bin/gzip -d
Nov 18 09:59:52 mail amavis[3487]: Found decoder for .gz at /usr/bin/gzip -d
Nov 18 09:59:52 mail amavis[3487]: No ext program for .bz2, tried: bzip2 -d
Nov 18 09:59:52 mail amavis[3487]: Found decoder for .xz at /usr/bin/xzdec
Nov 18 09:59:52 mail amavis[3487]: Found decoder for .lzma at /usr/bin/xz -dc --format=lzma
Nov 18 09:59:52 mail amavis[3487]: No ext program for .lrz, tried: lrzip -q -k -d -o -, lrzcat -q -k
Nov 18 09:59:52 mail amavis[3487]: No ext program for .lzo, tried: lzop -d
Nov 18 09:59:52 mail amavis[3487]: Found decoder for .rpm at /usr/bin/rpm2cpio
Nov 18 09:59:52 mail amavis[3487]: Found decoder for .cpio at /usr/bin/cpio
Nov 18 09:59:52 mail amavis[3487]: Found decoder for .tar at /usr/bin/cpio
Nov 18 09:59:52 mail amavis[3487]: Found decoder for .deb at /usr/bin/ar
Nov 18 09:59:52 mail amavis[3487]: No ext program for .rar, tried: unrar, rar
Nov 18 09:59:52 mail amavis[3487]: No ext program for .arj, tried: unarj, arj
Nov 18 09:59:52 mail amavis[3487]: No ext program for .arc, tried: nomarch, arc
Nov 18 09:59:52 mail amavis[3487]: No ext program for .zoo, tried: zoo, unzoo
Nov 18 09:59:52 mail amavis[3487]: No ext program for .doc, tried: ripole
Nov 18 09:59:52 mail amavis[3487]: No ext program for .cab, tried: cabextract
Nov 18 09:59:52 mail amavis[3487]: No ext program for .tnef, tried: tnef
Nov 18 09:59:52 mail amavis[3487]: Internal decoder for .tnef
Nov 18 09:59:52 mail amavis[3487]: No ext program for .zip, tried: 7za, 7z
Nov 18 09:59:52 mail amavis[3487]: No ext program for .kmz, tried: 7za, 7z
Nov 18 09:59:52 mail amavis[3487]: Internal decoder for .zip
Nov 18 09:59:52 mail amavis[3487]: Internal decoder for .kmz
Nov 18 09:59:52 mail amavis[3487]: No ext program for .7z, tried: 7zr, 7za, 7z
Nov 18 09:59:52 mail amavis[3487]: No ext program for .7z, tried: 7za, 7z
Nov 18 09:59:52 mail amavis[3487]: No ext program for .bz2, tried: 7za, 7z
Nov 18 09:59:52 mail amavis[3487]: No ext program for .jar, tried: 7z
Nov 18 09:59:52 mail amavis[3487]: No ext program for .arj, tried: 7z
Nov 18 09:59:52 mail amavis[3487]: No ext program for .rar, tried: 7z
Nov 18 09:59:52 mail amavis[3487]: No ext program for .swf, tried: 7z
Nov 18 09:59:52 mail amavis[3487]: No ext program for .lha, tried: 7z
Nov 18 09:59:52 mail amavis[3487]: No ext program for .iso, tried: 7z
Nov 18 09:59:52 mail amavis[3487]: No ext program for .cab, tried: 7z
Nov 18 09:59:52 mail amavis[3487]: No ext program for .exe, tried: unrar, rar; lha; unarj, arj
Nov 18 09:59:52 mail amavis[3487]: No decoder for .7z
Nov 18 09:59:52 mail amavis[3487]: No decoder for .F
Nov 18 09:59:52 mail amavis[3487]: No decoder for .arc
Nov 18 09:59:52 mail amavis[3487]: No decoder for .arj
Nov 18 09:59:52 mail amavis[3487]: No decoder for .bz2
Nov 18 09:59:52 mail amavis[3487]: No decoder for .cab
Nov 18 09:59:52 mail amavis[3487]: No decoder for .doc
Nov 18 09:59:52 mail amavis[3487]: No decoder for .exe
Nov 18 09:59:52 mail amavis[3487]: No decoder for .iso
Nov 18 09:59:52 mail amavis[3487]: No decoder for .jar
Nov 18 09:59:52 mail amavis[3487]: No decoder for .lha
Nov 18 09:59:52 mail amavis[3487]: No decoder for .lrz
Nov 18 09:59:52 mail amavis[3487]: No decoder for .lzo
Nov 18 09:59:52 mail amavis[3487]: No decoder for .rar
Nov 18 09:59:52 mail amavis[3487]: No decoder for .swf
Nov 18 09:59:52 mail amavis[3487]: No decoder for .zoo
Nov 18 09:59:52 mail amavis[3487]: Using primary internal av scanner code for ClamAV-clamd
Nov 18 09:59:52 mail amavis[3487]: initializing Mail::SpamAssassin (0)
Nov 18 09:59:52 mail zimbramon[1341]: 1341:info: Starting antispam via zmcontrol
Nov 18 09:59:52 mail amavis[3487]: SpamControl: init_pre_fork on SpamAssassin done
Nov 18 09:59:52 mail amavis[3487]: 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, Net/SMTP.pm
Nov 18 09:59:53 mail zimbramon[1341]: 1341:info: Starting antivirus via zmcontrol
Nov 18 09:59:55 mail clamd[3747]: Received 0 file descriptor(s) from systemd.
Nov 18 09:59:55 mail clamd[3747]: clamd daemon 0.99.2 (OS: linux-gnu, ARCH: x86_64, CPU: x86_64)
Nov 18 09:59:55 mail clamd[3747]: Log file size limited to 20971520 bytes.
Nov 18 09:59:55 mail clamd[3747]: Reading databases from /opt/zimbra/data/clamav/db
Nov 18 09:59:55 mail clamd[3747]: Not loading PUA signatures.
Nov 18 09:59:55 mail clamd[3747]: Bytecode: Security mode set to "TrustSigned".
Nov 18 10:00:00 mail clamd[3747]: Loaded 6344366 signatures.
Nov 18 10:00:01 mail postfix/postqueue[3792]: fatal: Queue report unavailable - mail system is down
Nov 18 10:00:01 mail zimbramon[3767]: 3767:info: 2017-11-18 10:00:01, QUEUE: 0 0
Nov 18 10:00:01 mail clamd[3747]: TCP: Bound to [127.0.0.1]:3310
Nov 18 10:00:01 mail clamd[3747]: TCP: Setting connection queue length to 200
Nov 18 10:00:01 mail clamd[3747]: LOCAL: Removing stale socket file /opt/zimbra/data/clamav/clamav.sock
Nov 18 10:00:01 mail clamd[3747]: LOCAL: Unix socket file /opt/zimbra/data/clamav/clamav.sock
Nov 18 10:00:01 mail clamd[3747]: LOCAL: Setting connection queue length to 200
Nov 18 10:00:01 mail clamd[3894]: Limits: Global size limit set to 102891520 bytes.
Nov 18 10:00:01 mail clamd[3894]: Limits: File size limit set to 102891520 bytes.
Nov 18 10:00:01 mail clamd[3894]: Limits: Recursion level limit set to 16.
Nov 18 10:00:01 mail clamd[3894]: Limits: Files limit set to 10000.
Nov 18 10:00:01 mail clamd[3894]: Limits: MaxEmbeddedPE limit set to 10485760 bytes.
Nov 18 10:00:01 mail clamd[3894]: Limits: MaxHTMLNormalize limit set to 10485760 bytes.
Nov 18 10:00:01 mail clamd[3894]: Limits: MaxHTMLNoTags limit set to 2097152 bytes.
Nov 18 10:00:01 mail clamd[3894]: Limits: MaxScriptNormalize limit set to 5242880 bytes.
Nov 18 10:00:01 mail clamd[3894]: Limits: MaxZipTypeRcg limit set to 1048576 bytes.
Nov 18 10:00:01 mail clamd[3894]: Limits: MaxPartitions limit set to 50.
Nov 18 10:00:01 mail clamd[3894]: Limits: MaxIconsPE limit set to 100.
Nov 18 10:00:01 mail clamd[3894]: Limits: MaxRecHWP3 limit set to 16.
Nov 18 10:00:01 mail clamd[3894]: Limits: PCREMatchLimit limit set to 10000.
Nov 18 10:00:01 mail clamd[3894]: Limits: PCRERecMatchLimit limit set to 5000.
Nov 18 10:00:01 mail clamd[3894]: Limits: PCREMaxFileSize limit set to 26214400.
Nov 18 10:00:01 mail clamd[3894]: Archive support enabled.
Nov 18 10:00:01 mail clamd[3894]: Archive: Blocking encrypted archives.
Nov 18 10:00:01 mail clamd[3894]: Algorithmic detection enabled.
Nov 18 10:00:01 mail clamd[3894]: Portable Executable support enabled.
Nov 18 10:00:01 mail clamd[3894]: ELF support enabled.
Nov 18 10:00:01 mail clamd[3894]: Mail files support enabled.
Nov 18 10:00:01 mail clamd[3894]: OLE2 support enabled.
Nov 18 10:00:01 mail clamd[3894]: PDF support enabled.
Nov 18 10:00:01 mail clamd[3894]: SWF support enabled.
Nov 18 10:00:01 mail clamd[3894]: HTML support enabled.
Nov 18 10:00:01 mail clamd[3894]: XMLDOCS support enabled.
Nov 18 10:00:01 mail clamd[3894]: HWP3 support enabled.
Nov 18 10:00:01 mail clamd[3894]: Self checking every 600 seconds.
Nov 18 10:00:04 mail zimbramon[1341]: 1341:info: Starting opendkim via zmcontrol
Nov 18 10:00:05 mail opendkim[4428]: OpenDKIM Filter v2.10.3 starting (args: -x /opt/zimbra/conf/opendkim.conf -u zimbra)
Nov 18 10:00:07 mail zimbramon[1341]: 1341:info: Starting snmp via zmcontrol
Nov 18 10:00:07 mail zimbramon[1341]: 1341:info: Starting spell via zmcontrol
Nov 18 10:00:07 mail zimbramon[1341]: 1341:info: Starting mta via zmcontrol
Nov 18 10:00:07 mail saslauthd[4555]: detach_tty : master pid is: 4555
Nov 18 10:00:07 mail saslauthd[4555]: ipc_init : listening on socket: /opt/zimbra/data/sasl2/state/mux
Nov 18 10:00:07 mail zimbramon[1341]: 1341:info: Starting stats via zmcontrol
Nov 18 10:00:07 mail zimbramon[1341]: 1341:info: Starting service via zmcontrol
Nov 18 10:00:07 mail zimbramon[1341]: 1341:info: Starting zimbra via zmcontrol
Nov 18 10:00:07 mail zimbramon[1341]: 1341:info: Starting zimbraAdmin via zmcontrol
Nov 18 10:00:07 mail zimbramon[1341]: 1341:info: Starting zimlet via zmcontrol
Nov 18 10:00:20 mail zmconfigd[1431]: Fetching All configs
Nov 18 10:00:20 mail zmconfigd[1431]: All configs fetched in 0.06 seconds
Nov 18 10:00:20 mail zmconfigd[1431]: Tracking service amavis
Nov 18 10:00:20 mail zmconfigd[1431]: Watchdog: service amavis now available for watchdog.
Nov 18 10:00:22 mail zmconfigd[1431]: Tracking service antispam
Nov 18 10:00:22 mail zmconfigd[1431]: Watchdog: service antispam now available for watchdog.
Nov 18 10:00:22 mail zmconfigd[1431]: Tracking service antivirus
Nov 18 10:00:22 mail zmconfigd[1431]: Watchdog: service antivirus now available for watchdog.
Nov 18 10:00:22 mail zmconfigd[1431]: Tracking service dnscache
Nov 18 10:00:22 mail zmconfigd[1431]: Watchdog: service dnscache now available for watchdog.
Nov 18 10:00:22 mail zmconfigd[1431]: Tracking service ldap
Nov 18 10:00:22 mail zmconfigd[1431]: Watchdog: service ldap now available for watchdog.
Nov 18 10:00:22 mail zmconfigd[1431]: Tracking service logger
Nov 18 10:00:22 mail zmconfigd[1431]: Watchdog: service logger now available for watchdog.
Nov 18 10:00:22 mail zmconfigd[1431]: Tracking service mailbox
Nov 18 10:00:22 mail zmconfigd[1431]: Watchdog: service mailbox now available for watchdog.
Nov 18 10:00:22 mail zmconfigd[1431]: Tracking service mailboxd
Nov 18 10:00:22 mail zmconfigd[1431]: Watchdog: service mailboxd now available for watchdog.
Nov 18 10:00:22 mail zmconfigd[1431]: Tracking service memcached
Nov 18 10:00:22 mail zmconfigd[1431]: Watchdog: service memcached now available for watchdog.
Nov 18 10:00:22 mail zmconfigd[1431]: Tracking service mta
Nov 18 10:00:22 mail zmconfigd[1431]: Watchdog: service mta now available for watchdog.
Nov 18 10:00:22 mail zmconfigd[1431]: Tracking service opendkim
Nov 18 10:00:22 mail zmconfigd[1431]: Watchdog: service opendkim now available for watchdog.
Nov 18 10:00:22 mail zmconfigd[1431]: Tracking service proxy
Nov 18 10:00:22 mail zmconfigd[1431]: Watchdog: service proxy now available for watchdog.
Nov 18 10:00:22 mail zmconfigd[1431]: Tracking service sasl
Nov 18 10:00:22 mail zmconfigd[1431]: Watchdog: service sasl now available for watchdog.
Nov 18 10:00:22 mail zmconfigd[1431]: Tracking service service
Nov 18 10:00:22 mail zmconfigd[1431]: Watchdog: service service now available for watchdog.
Nov 18 10:00:22 mail zmconfigd[1431]: Tracking service snmp
Nov 18 10:00:22 mail zmconfigd[1431]: Watchdog: service snmp now available for watchdog.
Nov 18 10:00:22 mail zmconfigd[1431]: Tracking service spell
Nov 18 10:00:22 mail zmconfigd[1431]: Watchdog: service spell now available for watchdog.
Nov 18 10:00:22 mail zmconfigd[1431]: Tracking service stats
Nov 18 10:00:22 mail zmconfigd[1431]: Watchdog: service stats now available for watchdog.
Nov 18 10:00:22 mail zmconfigd[1431]: Tracking service zimbra
Nov 18 10:00:22 mail zmconfigd[1431]: Watchdog: service zimbra now available for watchdog.
Nov 18 10:00:22 mail zmconfigd[1431]: Tracking service zimbraAdmin
Nov 18 10:00:22 mail zmconfigd[1431]: Watchdog: service zimbraAdmin now available for watchdog.
Nov 18 10:00:22 mail zmconfigd[1431]: Tracking service zimlet
Nov 18 10:00:22 mail zmconfigd[1431]: Watchdog: service zimlet now available for watchdog.
Nov 18 10:00:22 mail zmconfigd[1431]: Watchdog: service antivirus status is OK.
Nov 18 10:00:22 mail zmconfigd[1431]: All rewrite threads completed in 0.00 sec
Nov 18 10:00:22 mail zmconfigd[1431]: All restarts completed in 0.00 sec
Nov 18 10:01:22 mail zmconfigd[1431]: Fetching All configs
Nov 18 10:01:22 mail zmconfigd[1431]: All configs fetched in 0.04 seconds
Nov 18 10:01:25 mail zmconfigd[1431]: Watchdog: service antivirus status is OK.
Nov 18 10:01:25 mail zmconfigd[1431]: All rewrite threads completed in 0.00 sec
Nov 18 10:01:25 mail zmconfigd[1431]: All restarts completed in 0.00 sec
Nov 18 10:02:05 mail clamd[3894]: Reading databases from /opt/zimbra/data/clamav/db
Nov 18 10:02:08 mail clamd[3894]: Database correctly reloaded (4566324 signatures)
Nov 18 10:02:25 mail zmconfigd[1431]: Fetching All configs
All ZIMBRA services showed they were running, so only inside the log file i could see it was not...