Created
October 19, 2017 15:18
-
-
Save gordyt/e0c119c4ad34315f6dbb704fd7f88e75 to your computer and use it in GitHub Desktop.
/var/log/zimbra from zm-docker_zmc-mta service - illustrates amavisd issue
This file contains bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
| This appears to be caused by zmconfigd: | |
| After service has completed startup: | |
| root@zmc-mta:/opt/zimbra# sudo -i -u zimbra zmcontrol status | |
| Host zmc-mta | |
| amavis Stopped | |
| amavisd is not running. | |
| antispam Stopped | |
| zmamavisdctl is not running | |
| antivirus Stopped | |
| zmamavisdctl is not running | |
| archiving Stopped | |
| amavisd is not running. | |
| zmamavisdctl is not running | |
| mta Running | |
| opendkim Running | |
| stats Running | |
| zmconfigd Running | |
| Contents of /var/log/zimbra.log: | |
| Oct 19 15:05:02 zmc-mta zimbramon[635]: 635:info: Starting services initiated by zmcontrol | |
| Oct 19 15:05:02 zmc-mta zimbramon[635]: 635:info: Starting zmconfigd via zmcontrol | |
| Oct 19 15:05:15 zmc-mta zmconfigd[686]: zmconfigd started on zmc-mta with loglevel=3 pid=686 | |
| Oct 19 15:05:15 zmc-mta zmconfigd[686]: Fetching All configs | |
| Oct 19 15:05:16 zmc-mta zmconfigd[686]: All configs fetched in 0.94 seconds | |
| Oct 19 15:05:17 zmc-mta zmconfigd[686]: Rewrote: /opt/zimbra/conf/freshclam.conf with mode 600 (0.06 sec) | |
| Oct 19 15:05:17 zmc-mta zmconfigd[686]: Rewrote: /opt/zimbra/conf/opendkim-localnets.conf with mode 440 (0.00 sec) | |
| Oct 19 15:05:17 zmc-mta zmconfigd[686]: Rewrote: /opt/zimbra/conf/stats.conf with mode 440 (0.02 sec) | |
| Oct 19 15:05:17 zmc-mta zmconfigd[686]: Rewrote: /opt/zimbra/conf/opendkim.conf with mode 440 (0.01 sec) | |
| Oct 19 15:05:17 zmc-mta zmconfigd[686]: Rewrote: /opt/zimbra/common/conf/tag_as_foreign.re with mode 440 (0.01 sec) | |
| Oct 19 15:05:17 zmc-mta zmconfigd[686]: Rewrote: /opt/zimbra/conf/saslauthd.conf with mode 440 (0.02 sec) | |
| Oct 19 15:05:17 zmc-mta zmconfigd[686]: Rewrote: /opt/zimbra/conf/dspam.conf with mode 440 (0.04 sec) | |
| Oct 19 15:05:17 zmc-mta zmconfigd[686]: Rewrote: /opt/zimbra/conf/clamd.conf with mode 440 (0.12 sec) | |
| Oct 19 15:05:17 zmc-mta zmconfigd[686]: Rewrote: /opt/zimbra/conf/amavisd.conf with mode 440 (0.41 sec) | |
| Oct 19 15:05:17 zmc-mta zmconfigd[686]: Rewrote: /opt/zimbra/common/conf/tag_as_originating.re with mode 440 (0.00 sec) | |
| Oct 19 15:05:17 zmc-mta zmconfigd[686]: Rewrote: /opt/zimbra/common/conf/master.cf with mode 440 (0.03 sec) | |
| Oct 19 15:05:17 zmc-mta zmconfigd[686]: Rewrote: /opt/zimbra/data/spamassassin/localrules/salocal.cf with mode 440 (0.01 sec) | |
| Oct 19 15:05:17 zmc-mta zmconfigd[686]: Rewrote: /opt/zimbra/conf/sasl2/smtpd.conf with mode 440 (0.00 sec) | |
| Oct 19 15:05:17 zmc-mta zmconfigd[686]: Rewrote: /opt/zimbra/conf/mta_milter_options with mode 440 (0.00 sec) | |
| Oct 19 15:05:18 zmc-mta zmconfigd[686]: Rewrote: /opt/zimbra/conf/postfix_header_checks with mode 440 (0.05 sec) | |
| Oct 19 15:05:26 zmc-mta zmconfigd[686]: All rewrite threads completed in 8.93 sec | |
| Oct 19 15:05:26 zmc-mta zmconfigd[686]: All restarts completed in 0.00 sec | |
| Oct 19 15:05:26 zmc-mta zimbramon[635]: 635:info: Starting amavis via zmcontrol | |
| Oct 19 15:05:26 zmc-mta amavis-mc[1780]: amavis master process starting. daemonized as PID [1780], perl 5.022001 | |
| Oct 19 15:05:26 zmc-mta amavis-mc[1780]: Process [1782] started: /opt/zimbra/common/sbin/amavis-services msg-forwarder | |
| Oct 19 15:05:26 zmc-mta amavis-mc[1780]: Process [1783] started: /opt/zimbra/common/sbin/amavis-services childproc-minder | |
| Oct 19 15:05:26 zmc-mta amavis-mc[1780]: Process [1784] started: /opt/zimbra/common/sbin/amavis-services snmp-responder | |
| Oct 19 15:05:26 zmc-mta amavis-services[1782]: amavis-services-2.9.0 (20140506) task 'msg-forwarder' [1782] started. ZMQ::LibZMQ3 1.19, lib 4.1.4 | |
| Oct 19 15:05:26 zmc-mta amavis-services[1783]: amavis-services-2.9.0 (20140506) task 'childproc-minder' [1783] started. ZMQ::LibZMQ3 1.19, lib 4.1.4 | |
| Oct 19 15:05:26 zmc-mta amavis-services[1784]: amavis-services-2.9.0 (20140506) task 'snmp-responder' [1784] started. ZMQ::LibZMQ3 1.19, lib 4.1.4 | |
| Oct 19 15:05:27 zmc-mta amavis-services[1783]: childproc_minder: FLUSH process states | |
| Oct 19 15:05:56 zmc-mta zimbramon[635]: 635:info: Starting antispam via zmcontrol | |
| Oct 19 15:06:00 zmc-mta amavis[2061]: starting. /opt/zimbra/common/sbin/amavisd at localhost amavisd-new-2.10.1 (20141025), Unicode aware | |
| Oct 19 15:06:00 zmc-mta amavis[2061]: perl=5.022001, user=999, EUID: 999 (999); group=, EGID: 999 5 998 (999 5 998) | |
| Oct 19 15:06:00 zmc-mta amavis-services[1783]: childproc_minder: FLUSH process states | |
| Oct 19 15:06:00 zmc-mta amavis-services[1784]: snmp_responder: FLUSH snmp data | |
| Oct 19 15:06:00 zmc-mta amavis[2061]: SpamControl: init_pre_chroot on SpamAssassin done | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Net::Server: Process Backgrounded | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Net::Server: 2017/10/19-15:06:00 Amavis (type Net::Server::PreForkSimple) starting! pid(2072) | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Net::Server: Binding to UNIX socket file "/opt/zimbra/data/amavisd/amavisd.sock" | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Net::Server: Binding to TCP port 10024 on host 127.0.0.1 with IPv4 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Net::Server: Binding to TCP port 10026 on host 127.0.0.1 with IPv4 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Net::Server: Binding to TCP port 10032 on host 127.0.0.1 with IPv4 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Net::Server: Group Not Defined. Defaulting to EGID '999 5 998' | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Net::Server: User Not Defined. Defaulting to EUID '999' | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module Amavis::Conf 2.404 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module Archive::Zip 1.53 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module Compress::Raw::Zlib 2.069 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module Compress::Zlib 2.069 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module Crypt::OpenSSL::RSA 0.28 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module DB_File 1.835 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module Digest::MD5 2.54 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module Digest::SHA 5.95 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module Encode 2.72 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module File::LibMagic 1.15 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module File::Temp 0.2304 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module IO::Socket::INET6 2.72 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module IO::Socket::IP 0.37 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module MIME::Entity 5.507 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module MIME::Parser 5.507 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module MIME::Tools 5.507 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module Mail::DKIM::Signer 0.4 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module Mail::DKIM::Verifier 0.4 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module Mail::Header 2.14 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module Mail::Internet 2.14 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module Mail::SPF v2.009 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module Mail::SpamAssassin 3.004001 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module Net::DNS 1.04 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module Net::LDAP 0.65 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module Net::LibIDN 0.12 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module Net::Server 2.008 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module NetAddr::IP 4.078 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module Scalar::Util 1.41 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module Socket 2.020 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module Socket6 0.25 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module Time::HiRes 1.9726 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module URI 1.69 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module Unix::Getrusage 0.03 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module Unix::Syslog 1.1 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Module ZMQ::LibZMQ3 1.19 | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Amavis::ZMQ code loaded | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Amavis::DB code NOT loaded | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: SQL base code NOT loaded | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: SQL::Log code NOT loaded | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: SQL::Quarantine NOT loaded | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Lookup::SQL code NOT loaded | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Lookup::LDAP code loaded | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: AM.PDP-in proto code loaded | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: SMTP-in proto code loaded | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Courier proto code NOT loaded | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: SMTP-out proto code loaded | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Pipe-out proto code NOT loaded | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: BSMTP-out proto code NOT loaded | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Local-out proto code loaded | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: OS_Fingerprint code NOT loaded | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: ANTI-VIRUS code loaded | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: ANTI-SPAM code loaded | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: ANTI-SPAM-EXT code NOT loaded | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: ANTI-SPAM-C code NOT loaded | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: ANTI-SPAM-SA code loaded | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Unpackers code loaded | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: DKIM code loaded | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Tools code NOT loaded | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Found $file at /usr/bin/file | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No $altermime, not using it | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Internal decoder for .mail | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No ext program for .F, tried: unfreeze, freeze -d, melt, fcat | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Found decoder for .Z at /bin/uncompress | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Found decoder for .gz at /bin/gzip -d | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Found decoder for .bz2 at /bin/bzip2 -d | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Found decoder for .xz at /usr/bin/xz -dc | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Found decoder for .lzma at /usr/bin/xz -dc --format=lzma | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No ext program for .lrz, tried: lrzip -q -k -d -o -, lrzcat -q -k | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No ext program for .lzo, tried: lzop -d | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No ext program for .rpm, tried: rpm2cpio.pl, rpm2cpio | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Found decoder for .cpio at /bin/pax | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Found decoder for .tar at /bin/pax | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Found decoder for .deb at /usr/bin/ar | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No ext program for .rar, tried: unrar, rar | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No ext program for .arj, tried: unarj, arj | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No ext program for .arc, tried: nomarch, arc | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No ext program for .zoo, tried: zoo, unzoo | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No ext program for .doc, tried: ripole | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No ext program for .cab, tried: cabextract | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No ext program for .tnef, tried: tnef | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Internal decoder for .tnef | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No ext program for .zip, tried: 7za, 7z | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No ext program for .kmz, tried: 7za, 7z | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Internal decoder for .zip | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Internal decoder for .kmz | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No ext program for .7z, tried: 7zr, 7za, 7z | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No ext program for .7z, tried: 7za, 7z | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No ext program for .jar, tried: 7z | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No ext program for .arj, tried: 7z | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No ext program for .rar, tried: 7z | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No ext program for .swf, tried: 7z | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No ext program for .lha, tried: 7z | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No ext program for .iso, tried: 7z | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No ext program for .cab, tried: 7z | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No ext program for .rpm, tried: 7z | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No ext program for .exe, tried: unrar, rar; lha; unarj, arj | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No decoder for .7z | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No decoder for .F | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No decoder for .arc | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No decoder for .arj | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No decoder for .cab | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No decoder for .doc | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No decoder for .exe | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No decoder for .iso | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No decoder for .jar | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No decoder for .lha | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No decoder for .lrz | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No decoder for .lzo | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No decoder for .rar | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No decoder for .rpm | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No decoder for .swf | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: No decoder for .zoo | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: Using primary internal av scanner code for ClamAV-clamd | |
| Oct 19 15:06:00 zmc-mta amavis[2072]: initializing Mail::SpamAssassin (0) | |
| Oct 19 15:06:00 zmc-mta zimbramon[635]: 635:info: Starting antivirus via zmcontrol | |
| Oct 19 15:06:00 zmc-mta clamd[2105]: Received 0 file descriptor(s) from systemd. | |
| Oct 19 15:06:00 zmc-mta clamd[2105]: clamd daemon 0.99.2 (OS: linux-gnu, ARCH: x86_64, CPU: x86_64) | |
| Oct 19 15:06:00 zmc-mta clamd[2105]: Log file size limited to 20971520 bytes. | |
| Oct 19 15:06:00 zmc-mta clamd[2105]: Reading databases from /opt/zimbra/data/clamav/db | |
| Oct 19 15:06:00 zmc-mta clamd[2105]: Not loading PUA signatures. | |
| Oct 19 15:06:00 zmc-mta clamd[2105]: Bytecode: Security mode set to "TrustSigned". | |
| Oct 19 15:06:02 zmc-mta amavis[2072]: SpamControl: init_pre_fork on SpamAssassin done | |
| Oct 19 15:06:02 zmc-mta amavis[2072]: extra modules loaded after daemonizing/chrooting: /etc/perl/Net/libnet.cfg, /opt/zimbra/common/lib/perl5/x86_64-linux-gnu-thread-multi/auto/Net/SSLeay/autosplit.ix, /opt/zimbra/common/lib/perl5/x86_64-linux-gnu-thread-multi/auto/Net/SSLeay/randomize.al, IO/Socket/SSL.pm, IO/Socket/SSL/PublicSuffix.pm, Mail/SpamAssassin/Plugin/FreeMail.pm, Mail/SpamAssassin/Plugin/SpamCop.pm, Net/Cmd.pm, Net/Config.pm, Net/SMTP.pm, Net/SSLeay.pm | |
| Oct 19 15:06:05 zmc-mta clamd[2105]: Loaded 4452245 signatures. | |
| Oct 19 15:06:06 zmc-mta clamd[2105]: TCP: Bound to [127.0.0.1]:3310 | |
| Oct 19 15:06:06 zmc-mta clamd[2105]: TCP: Setting connection queue length to 200 | |
| Oct 19 15:06:06 zmc-mta clamd[2105]: TCP: Cannot bind to [127.0.0.1]:3310: Address already in use | |
| Oct 19 15:06:06 zmc-mta clamd[2105]: LOCAL: Unix socket file /opt/zimbra/data/clamav/clamav.sock | |
| Oct 19 15:06:06 zmc-mta clamd[2105]: LOCAL: Setting connection queue length to 200 | |
| Oct 19 15:06:06 zmc-mta clamd[2139]: Limits: Global size limit set to 10240000 bytes. | |
| Oct 19 15:06:06 zmc-mta clamd[2139]: Limits: File size limit set to 10240000 bytes. | |
| Oct 19 15:06:06 zmc-mta clamd[2139]: Limits: Recursion level limit set to 16. | |
| Oct 19 15:06:06 zmc-mta clamd[2139]: Limits: Files limit set to 10000. | |
| Oct 19 15:06:06 zmc-mta clamd[2139]: Limits: MaxEmbeddedPE limit set to 10485760 bytes. | |
| Oct 19 15:06:06 zmc-mta clamd[2139]: Limits: MaxHTMLNormalize limit set to 10485760 bytes. | |
| Oct 19 15:06:06 zmc-mta clamd[2139]: Limits: MaxHTMLNoTags limit set to 2097152 bytes. | |
| Oct 19 15:06:06 zmc-mta clamd[2139]: Limits: MaxScriptNormalize limit set to 5242880 bytes. | |
| Oct 19 15:06:06 zmc-mta clamd[2139]: Limits: MaxZipTypeRcg limit set to 1048576 bytes. | |
| Oct 19 15:06:06 zmc-mta clamd[2139]: Limits: MaxPartitions limit set to 50. | |
| Oct 19 15:06:06 zmc-mta clamd[2139]: Limits: MaxIconsPE limit set to 100. | |
| Oct 19 15:06:06 zmc-mta clamd[2139]: Limits: MaxRecHWP3 limit set to 16. | |
| Oct 19 15:06:06 zmc-mta clamd[2139]: Limits: PCREMatchLimit limit set to 10000. | |
| Oct 19 15:06:06 zmc-mta clamd[2139]: Limits: PCRERecMatchLimit limit set to 5000. | |
| Oct 19 15:06:06 zmc-mta clamd[2139]: Limits: PCREMaxFileSize limit set to 26214400. | |
| Oct 19 15:06:06 zmc-mta clamd[2139]: Archive support enabled. | |
| Oct 19 15:06:06 zmc-mta clamd[2139]: Archive: Blocking encrypted archives. | |
| Oct 19 15:06:06 zmc-mta clamd[2139]: Algorithmic detection enabled. | |
| Oct 19 15:06:06 zmc-mta clamd[2139]: Portable Executable support enabled. | |
| Oct 19 15:06:06 zmc-mta clamd[2139]: ELF support enabled. | |
| Oct 19 15:06:06 zmc-mta clamd[2139]: Mail files support enabled. | |
| Oct 19 15:06:06 zmc-mta clamd[2139]: OLE2 support enabled. | |
| Oct 19 15:06:06 zmc-mta clamd[2139]: PDF support enabled. | |
| Oct 19 15:06:06 zmc-mta clamd[2139]: SWF support enabled. | |
| Oct 19 15:06:06 zmc-mta clamd[2139]: HTML support enabled. | |
| Oct 19 15:06:06 zmc-mta clamd[2139]: XMLDOCS support enabled. | |
| Oct 19 15:06:06 zmc-mta clamd[2139]: HWP3 support enabled. | |
| Oct 19 15:06:06 zmc-mta clamd[2139]: Self checking every 600 seconds. | |
| Oct 19 15:06:10 zmc-mta zimbramon[635]: 635:info: Starting opendkim via zmcontrol | |
| Oct 19 15:06:11 zmc-mta opendkim[2157]: OpenDKIM Filter v2.10.3 starting (args: -x /opt/zimbra/conf/opendkim.conf -u zimbra) | |
| Oct 19 15:06:13 zmc-mta zimbramon[635]: 635:info: Starting archiving via zmcontrol | |
| Oct 19 15:06:13 zmc-mta zimbramon[635]: 635:info: Starting mta via zmcontrol | |
| Oct 19 15:06:13 zmc-mta saslauthd[2193]: detach_tty : master pid is: 2193 | |
| Oct 19 15:06:13 zmc-mta saslauthd[2193]: ipc_init : listening on socket: /opt/zimbra/data/sasl2/state/mux | |
| Oct 19 15:06:13 zmc-mta /postfix-script[2340]: starting the Postfix mail system | |
| Oct 19 15:06:13 zmc-mta postfix/master[2342]: daemon started -- version 3.1.1, configuration /opt/zimbra/common/conf | |
| Oct 19 15:06:13 zmc-mta zimbramon[635]: 635:info: Starting stats via zmcontrol | |
| Oct 19 15:06:26 zmc-mta zmconfigd[686]: Fetching All configs | |
| Oct 19 15:06:26 zmc-mta zmconfigd[686]: All configs fetched in 0.14 seconds | |
| Oct 19 15:06:26 zmc-mta zmconfigd[686]: Tracking service amavis | |
| Oct 19 15:06:26 zmc-mta zmconfigd[686]: Watchdog: service amavis now available for watchdog. | |
| Oct 19 15:06:28 zmc-mta zmconfigd[686]: Tracking service antispam | |
| Oct 19 15:06:28 zmc-mta zmconfigd[686]: Watchdog: service antispam now available for watchdog. | |
| Oct 19 15:06:28 zmc-mta zmconfigd[686]: Tracking service antivirus | |
| Oct 19 15:06:28 zmc-mta zmconfigd[686]: Watchdog: service antivirus now available for watchdog. | |
| Oct 19 15:06:29 zmc-mta zmconfigd[686]: Tracking service archiving | |
| Oct 19 15:06:29 zmc-mta zmconfigd[686]: Watchdog: service archiving now available for watchdog. | |
| Oct 19 15:06:29 zmc-mta zmconfigd[686]: Tracking service mta | |
| Oct 19 15:06:29 zmc-mta zmconfigd[686]: Watchdog: service mta now available for watchdog. | |
| Oct 19 15:06:29 zmc-mta zmconfigd[686]: Tracking service opendkim | |
| Oct 19 15:06:29 zmc-mta zmconfigd[686]: Watchdog: service opendkim now available for watchdog. | |
| Oct 19 15:06:29 zmc-mta zmconfigd[686]: Tracking service sasl | |
| Oct 19 15:06:29 zmc-mta zmconfigd[686]: Watchdog: service sasl now available for watchdog. | |
| Oct 19 15:06:29 zmc-mta zmconfigd[686]: Tracking service stats | |
| Oct 19 15:06:29 zmc-mta zmconfigd[686]: Watchdog: service stats now available for watchdog. | |
| Oct 19 15:06:29 zmc-mta zmconfigd[686]: Watchdog: service antivirus status is OK. | |
| Oct 19 15:06:29 zmc-mta zmconfigd[686]: All rewrite threads completed in 0.01 sec | |
| Oct 19 15:06:29 zmc-mta zmconfigd[686]: All restarts completed in 0.00 sec | |
| Oct 19 15:07:05 zmc-mta clamd[2139]: Reading databases from /opt/zimbra/data/clamav/db | |
| Oct 19 15:07:12 zmc-mta clamd[2139]: Database correctly reloaded (6317983 signatures) | |
| Oct 19 15:07:29 zmc-mta zmconfigd[686]: Fetching All configs | |
| Oct 19 15:07:29 zmc-mta zmconfigd[686]: All configs fetched in 0.06 seconds | |
| Oct 19 15:07:32 zmc-mta zmconfigd[686]: Watchdog: service antivirus status is OK. | |
| Oct 19 15:07:32 zmc-mta zmconfigd[686]: Var zimbraQuarantineBannedItems changed from 'FALSE' -> 'TRUE' | |
| Oct 19 15:07:32 zmc-mta zmconfigd[686]: Var zimbraAmavisQuarantineAccount changed from 'None' -> 'ham.@zmc.com' | |
| Oct 19 15:07:32 zmc-mta zmconfigd[686]: Rewrote: /opt/zimbra/conf/amavisd.conf with mode 440 (0.02 sec) | |
| Oct 19 15:07:32 zmc-mta zmconfigd[686]: All rewrite threads completed in 0.05 sec | |
| Oct 19 15:07:32 zmc-mta zmconfigd[686]: controlProcess antivirus restart (-1) | |
| Oct 19 15:07:32 zmc-mta zmconfigd[686]: CONTROL antivirus: bin/zmclamdctl restart norewrite | |
| Oct 19 15:07:32 zmc-mta zmconfigd[686]: antivirus restart initiated from zmconfigd | |
| Oct 19 15:07:33 zmc-mta clamd[2139]: Pid file removed. | |
| Oct 19 15:07:33 zmc-mta clamd[2139]: --- Stopped at Thu Oct 19 15:07:33 2017 | |
| Oct 19 15:07:33 zmc-mta clamd[2139]: Socket file removed. | |
| Oct 19 15:07:34 zmc-mta clamd[3730]: Received 0 file descriptor(s) from systemd. | |
| Oct 19 15:07:34 zmc-mta clamd[3730]: clamd daemon 0.99.2 (OS: linux-gnu, ARCH: x86_64, CPU: x86_64) | |
| Oct 19 15:07:34 zmc-mta clamd[3730]: Log file size limited to 20971520 bytes. | |
| Oct 19 15:07:34 zmc-mta clamd[3730]: Reading databases from /opt/zimbra/data/clamav/db | |
| Oct 19 15:07:34 zmc-mta clamd[3730]: Not loading PUA signatures. | |
| Oct 19 15:07:34 zmc-mta clamd[3730]: Bytecode: Security mode set to "TrustSigned". | |
| Oct 19 15:07:41 zmc-mta clamd[3730]: Loaded 6317983 signatures. | |
| Oct 19 15:07:42 zmc-mta clamd[3730]: TCP: Bound to [127.0.0.1]:3310 | |
| Oct 19 15:07:42 zmc-mta clamd[3730]: TCP: Setting connection queue length to 200 | |
| Oct 19 15:07:42 zmc-mta clamd[3730]: TCP: Cannot bind to [127.0.0.1]:3310: Address already in use | |
| Oct 19 15:07:42 zmc-mta clamd[3730]: LOCAL: Unix socket file /opt/zimbra/data/clamav/clamav.sock | |
| Oct 19 15:07:42 zmc-mta clamd[3730]: LOCAL: Setting connection queue length to 200 | |
| Oct 19 15:07:42 zmc-mta clamd[3733]: Limits: Global size limit set to 10240000 bytes. | |
| Oct 19 15:07:42 zmc-mta clamd[3733]: Limits: File size limit set to 10240000 bytes. | |
| Oct 19 15:07:42 zmc-mta clamd[3733]: Limits: Recursion level limit set to 16. | |
| Oct 19 15:07:42 zmc-mta clamd[3733]: Limits: Files limit set to 10000. | |
| Oct 19 15:07:42 zmc-mta clamd[3733]: Limits: MaxEmbeddedPE limit set to 10485760 bytes. | |
| Oct 19 15:07:42 zmc-mta clamd[3733]: Limits: MaxHTMLNormalize limit set to 10485760 bytes. | |
| Oct 19 15:07:42 zmc-mta clamd[3733]: Limits: MaxHTMLNoTags limit set to 2097152 bytes. | |
| Oct 19 15:07:42 zmc-mta clamd[3733]: Limits: MaxScriptNormalize limit set to 5242880 bytes. | |
| Oct 19 15:07:42 zmc-mta clamd[3733]: Limits: MaxZipTypeRcg limit set to 1048576 bytes. | |
| Oct 19 15:07:42 zmc-mta clamd[3733]: Limits: MaxPartitions limit set to 50. | |
| Oct 19 15:07:42 zmc-mta clamd[3733]: Limits: MaxIconsPE limit set to 100. | |
| Oct 19 15:07:42 zmc-mta clamd[3733]: Limits: MaxRecHWP3 limit set to 16. | |
| Oct 19 15:07:42 zmc-mta clamd[3733]: Limits: PCREMatchLimit limit set to 10000. | |
| Oct 19 15:07:42 zmc-mta clamd[3733]: Limits: PCRERecMatchLimit limit set to 5000. | |
| Oct 19 15:07:42 zmc-mta clamd[3733]: Limits: PCREMaxFileSize limit set to 26214400. | |
| Oct 19 15:07:42 zmc-mta clamd[3733]: Archive support enabled. | |
| Oct 19 15:07:42 zmc-mta clamd[3733]: Archive: Blocking encrypted archives. | |
| Oct 19 15:07:42 zmc-mta clamd[3733]: Algorithmic detection enabled. | |
| Oct 19 15:07:42 zmc-mta clamd[3733]: Portable Executable support enabled. | |
| Oct 19 15:07:42 zmc-mta clamd[3733]: ELF support enabled. | |
| Oct 19 15:07:42 zmc-mta clamd[3733]: Mail files support enabled. | |
| Oct 19 15:07:42 zmc-mta clamd[3733]: OLE2 support enabled. | |
| Oct 19 15:07:42 zmc-mta clamd[3733]: PDF support enabled. | |
| Oct 19 15:07:42 zmc-mta clamd[3733]: SWF support enabled. | |
| Oct 19 15:07:42 zmc-mta clamd[3733]: HTML support enabled. | |
| Oct 19 15:07:42 zmc-mta clamd[3733]: XMLDOCS support enabled. | |
| Oct 19 15:07:42 zmc-mta clamd[3733]: HWP3 support enabled. | |
| Oct 19 15:07:42 zmc-mta clamd[3733]: Self checking every 600 seconds. | |
| Oct 19 15:07:44 zmc-mta zmconfigd[686]: controlProcess amavis restart (-1) | |
| Oct 19 15:07:44 zmc-mta zmconfigd[686]: CONTROL amavis: bin/zmamavisdctl restart norewrite | |
| Oct 19 15:07:44 zmc-mta zmconfigd[686]: amavis restart initiated from zmconfigd | |
| Oct 19 15:07:44 zmc-mta amavis[2072]: Net::Server: 2017/10/19-15:07:44 Server closing! | |
| Oct 19 15:07:49 zmc-mta amavis-mc[1780]: Master process shutting down | |
| Oct 19 15:07:49 zmc-mta amavis-mc[1780]: Terminating process [1782] (/opt/zimbra/common/sbin/amavis-services msg-forwarder) | |
| Oct 19 15:07:49 zmc-mta amavis-mc[1780]: Terminating process [1783] (/opt/zimbra/common/sbin/amavis-services childproc-minder) | |
| Oct 19 15:07:49 zmc-mta amavis-mc[1780]: Terminating process [1784] (/opt/zimbra/common/sbin/amavis-services snmp-responder) | |
| Oct 19 15:07:49 zmc-mta amavis-services[1782]: Task 'msg-forwarder' [1782] shutting down | |
| Oct 19 15:07:49 zmc-mta amavis-services[1783]: Task 'childproc-minder' [1783] shutting down | |
| Oct 19 15:07:49 zmc-mta amavis-services[1782]: msg-forwarder closing inner socket | |
| Oct 19 15:07:49 zmc-mta amavis-services[1783]: childproc-minder closing inner socket | |
| Oct 19 15:07:49 zmc-mta amavis-services[1783]: childproc-minder closing outer socket | |
| Oct 19 15:07:49 zmc-mta amavis-services[1783]: childproc-minder closing context | |
| Oct 19 15:07:49 zmc-mta amavis-services[1782]: msg-forwarder closing outer socket | |
| Oct 19 15:07:49 zmc-mta amavis-services[1782]: msg-forwarder closing context | |
| Oct 19 15:07:49 zmc-mta amavis-services[1784]: Task 'snmp-responder' [1784] shutting down | |
| Oct 19 15:07:49 zmc-mta amavis-services[1784]: snmp-responder closing outer socket | |
| Oct 19 15:07:49 zmc-mta amavis-services[1784]: snmp-responder closing SNMP socket | |
| Oct 19 15:07:49 zmc-mta amavis-services[1784]: snmp-responder closing context | |
| Oct 19 15:07:49 zmc-mta amavis-services[1783]: Task 'childproc-minder' [1783] exiting: TERM | |
| Oct 19 15:07:49 zmc-mta amavis-services[1784]: Task 'snmp-responder' [1784] exiting: TERM | |
| Oct 19 15:07:49 zmc-mta amavis-services[1782]: Task 'msg-forwarder' [1782] exiting: TERM | |
| Oct 19 15:07:49 zmc-mta amavis-mc[1780]: Process [1783] exited (/opt/zimbra/common/sbin/amavis-services childproc-minder) after 142.8 s: exit 0 | |
| Oct 19 15:07:49 zmc-mta amavis-mc[1780]: Process [1784] exited (/opt/zimbra/common/sbin/amavis-services snmp-responder) after 142.8 s: exit 0 | |
| Oct 19 15:07:49 zmc-mta amavis-mc[1780]: Process [1782] exited (/opt/zimbra/common/sbin/amavis-services msg-forwarder) after 142.8 s: exit 0 | |
| Oct 19 15:07:51 zmc-mta amavis-mc[1780]: Master process exiting: TERM | |
| Oct 19 15:07:54 zmc-mta amavis-mc[3776]: amavis master process starting. daemonized as PID [3776], perl 5.022001 | |
| Oct 19 15:07:54 zmc-mta amavis-mc[3776]: Process [3780] started: /opt/zimbra/common/sbin/amavis-services msg-forwarder | |
| Oct 19 15:07:54 zmc-mta amavis-mc[3776]: Process [3781] started: /opt/zimbra/common/sbin/amavis-services childproc-minder | |
| Oct 19 15:07:54 zmc-mta amavis-mc[3776]: Process [3782] started: /opt/zimbra/common/sbin/amavis-services snmp-responder | |
| Oct 19 15:07:54 zmc-mta amavis-services[3781]: amavis-services-2.9.0 (20140506) task 'childproc-minder' [3781] started. ZMQ::LibZMQ3 1.19, lib 4.1.4 | |
| Oct 19 15:07:54 zmc-mta amavis-services[3780]: amavis-services-2.9.0 (20140506) task 'msg-forwarder' [3780] started. ZMQ::LibZMQ3 1.19, lib 4.1.4 | |
| Oct 19 15:07:54 zmc-mta amavis-services[3782]: amavis-services-2.9.0 (20140506) task 'snmp-responder' [3782] started. ZMQ::LibZMQ3 1.19, lib 4.1.4 | |
| Oct 19 15:07:55 zmc-mta amavis-services[3781]: childproc_minder: FLUSH process states | |
| Oct 19 15:08:24 zmc-mta zmconfigd[686]: controlProcess mta restart (-1) | |
| Oct 19 15:08:24 zmc-mta zmconfigd[686]: CONTROL mta: bin/zmmtactl reload norewrite | |
| Oct 19 15:08:24 zmc-mta zmconfigd[686]: mta reload initiated from zmconfigd | |
| Oct 19 15:08:24 zmc-mta saslauthd[2193]: server_exit : master exited: 2193 | |
| Oct 19 15:08:25 zmc-mta saslauthd[3938]: detach_tty : master pid is: 3938 | |
| Oct 19 15:08:25 zmc-mta saslauthd[3938]: ipc_init : listening on socket: /opt/zimbra/data/sasl2/state/mux | |
| Oct 19 15:08:26 zmc-mta /postfix-script[3971]: refreshing the Postfix mail system | |
| Oct 19 15:08:26 zmc-mta postfix/master[2342]: reload -- version 3.1.1, configuration /opt/zimbra/common/conf | |
| Oct 19 15:08:26 zmc-mta zmconfigd[686]: All restarts completed in 53.85 sec | |
| Oct 19 15:09:26 zmc-mta zmconfigd[686]: Fetching All configs | |
| Oct 19 15:09:26 zmc-mta zmconfigd[686]: All configs fetched in 0.08 seconds | |
| Oct 19 15:09:26 zmc-mta zmconfigd[686]: Service status change: zmc-mta amavis changed from running to stopped | |
| Oct 19 15:09:26 zmc-mta zmconfigd[686]: Tracking service amavis | |
| Oct 19 15:09:27 zmc-mta zmconfigd[686]: Service status change: zmc-mta antispam changed from running to stopped | |
| Oct 19 15:09:27 zmc-mta zmconfigd[686]: Tracking service antivirus | |
| Oct 19 15:09:27 zmc-mta zmconfigd[686]: Watchdog: service antivirus now available for watchdog. | |
| Oct 19 15:09:27 zmc-mta zmconfigd[686]: Service status change: zmc-mta archiving changed from running to stopped | |
| Oct 19 15:09:27 zmc-mta zmconfigd[686]: Tracking service mta | |
| Oct 19 15:09:27 zmc-mta zmconfigd[686]: Watchdog: service mta now available for watchdog. | |
| Oct 19 15:09:28 zmc-mta zmconfigd[686]: Watchdog: service antivirus status is OK. | |
| Oct 19 15:09:28 zmc-mta zmconfigd[686]: All rewrite threads completed in 0.03 sec | |
| Oct 19 15:09:28 zmc-mta zmconfigd[686]: All restarts completed in 0.00 sec | |
| Oct 19 15:10:28 zmc-mta zmconfigd[686]: Fetching All configs | |
| Oct 19 15:10:28 zmc-mta zmconfigd[686]: All configs fetched in 0.04 seconds | |
| Oct 19 15:10:28 zmc-mta zmconfigd[686]: Tracking service amavis | |
| Oct 19 15:10:30 zmc-mta zmconfigd[686]: Watchdog: service antivirus status is OK. | |
| Oct 19 15:10:30 zmc-mta zmconfigd[686]: All rewrite threads completed in 0.00 sec | |
| Oct 19 15:10:30 zmc-mta zmconfigd[686]: All restarts completed in 0.00 sec | |
| Oct 19 15:11:30 zmc-mta zmconfigd[686]: Fetching All configs | |
| Oct 19 15:11:30 zmc-mta zmconfigd[686]: All configs fetched in 0.05 seconds | |
| Oct 19 15:11:30 zmc-mta zmconfigd[686]: Tracking service amavis | |
| Oct 19 15:11:32 zmc-mta zmconfigd[686]: Watchdog: service antivirus status is OK. | |
| Oct 19 15:11:32 zmc-mta zmconfigd[686]: All rewrite threads completed in 0.00 sec | |
| Oct 19 15:11:32 zmc-mta zmconfigd[686]: All restarts completed in 0.00 sec | |
| Oct 19 15:12:32 zmc-mta zmconfigd[686]: Fetching All configs | |
| Oct 19 15:12:32 zmc-mta zmconfigd[686]: All configs fetched in 0.04 seconds | |
| Oct 19 15:12:32 zmc-mta zmconfigd[686]: Tracking service amavis | |
| Oct 19 15:12:34 zmc-mta zmconfigd[686]: Watchdog: service antivirus status is OK. | |
| Oct 19 15:12:34 zmc-mta zmconfigd[686]: All rewrite threads completed in 0.00 sec | |
| Oct 19 15:12:34 zmc-mta zmconfigd[686]: All restarts completed in 0.00 sec | |
| Oct 19 15:13:34 zmc-mta zmconfigd[686]: Fetching All configs | |
| Oct 19 15:13:34 zmc-mta zmconfigd[686]: All configs fetched in 0.06 seconds | |
| Oct 19 15:13:35 zmc-mta zmconfigd[686]: Tracking service amavis | |
| Oct 19 15:13:36 zmc-mta zmconfigd[686]: Watchdog: service antivirus status is OK. | |
| Oct 19 15:13:37 zmc-mta zmconfigd[686]: All rewrite threads completed in 0.00 sec | |
| Oct 19 15:13:37 zmc-mta zmconfigd[686]: All restarts completed in 0.00 sec | |
| Oct 19 15:14:37 zmc-mta zmconfigd[686]: Fetching All configs | |
| Oct 19 15:14:37 zmc-mta zmconfigd[686]: All configs fetched in 0.04 seconds | |
| Oct 19 15:14:37 zmc-mta zmconfigd[686]: Tracking service amavis | |
| Oct 19 15:14:39 zmc-mta zmconfigd[686]: Watchdog: service antivirus status is OK. | |
| Oct 19 15:14:39 zmc-mta zmconfigd[686]: All rewrite threads completed in 0.00 sec | |
| Oct 19 15:14:39 zmc-mta zmconfigd[686]: All restarts completed in 0.00 sec |
Sign up for free
to join this conversation on GitHub.
Already have an account?
Sign in to comment