zmmailboxdctl is not running + java "no valid keystore"

Ask questions about your setup or get help installing ZCS server (ZD section below).
User avatar
msquadrat
Advanced member
Advanced member
Posts: 183
Joined: Mon Oct 14, 2013 10:09 am

Re: zmmailboxdctl is not running + java "no valid keystore"

Post by msquadrat »

I hadn't noticed that the recommended order in the multi server install guide changed at some point in the past. I install multi-server clusters since version 5 or so and my order always is:

1. LDAP
2. Mailbox
3. Proxy
4. MTA

The order of the latter two actually isn't that important.

I think that's also the order of the chapters in the install guide. This works for me for years now. You'll have to fix up some attributes when you install the MTA after the mailbox server but that order generally works fine.
vijendra
Posts: 14
Joined: Tue Mar 13, 2018 10:07 pm

Re: zmmailboxdctl is not running + java "no valid keystore"

Post by vijendra »

I have like similer issue but in my /opt/zimbra/log/zmmailboxd.out its says

at org.eclipse.jetty.start.Main.invokeMain(Main.java:473)
at org.eclipse.jetty.start.Main.start(Main.java:615)
at org.eclipse.jetty.start.Main.main(Main.java:96)
2018-03-15 14:10:04.912:WARN:oejuc.AbstractLifeCycle:FAILED org.mortbay.setuid.SetUIDServer@462d40bf: MultiException[java.io.FileNotFoundException: /opt/zimbra/jetty-distribution-7.6.12.v20130726/webapps/zimbra/WEB-INF/web.xml (Permission denied), java.io.FileNotFoundException: /opt/zimbra/jetty-distribution-7.6.12.v20130726/webapps/zimbraAdmin/WEB-INF/web.xml (Permission denied), java.io.FileNotFoundException: /opt/zimbra/jetty-distribution-7.6.12.v20130726/webapps/zimlet/WEB-INF/web.xml (Permission denied), java.io.IOException: Cannot write log directory /opt/zimbra/log]
MultiException[java.io.FileNotFoundException: /opt/zimbra/jetty-distribution-7.6.12.v20130726/webapps/zimbra/WEB-INF/web.xml (Permission denied), java.io.FileNotFoundException: /opt/zimbra/jetty-distribution-7.6.12.v20130726/webapps/zimbraAdmin/WEB-INF/web.xml (Permission denied), java.io.FileNotFoundException: /opt/zimbra/jetty-distribution-7.6.12.v20130726/webapps/zimlet/WEB-INF/web.xml (Permission denied), java.io.IOException: Cannot write log directory /opt/zimbra/log]
at org.eclipse.jetty.server.Server.doStart(Server.java:274)
at org.mortbay.setuid.SetUIDServer.doStart(SetUIDServer.java:178)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:64)
at org.eclipse.jetty.xml.XmlConfiguration$1.run(XmlConfiguration.java:1259)
at java.security.AccessController.doPrivileged(Native Method)
at org.eclipse.jetty.xml.XmlConfiguration.main(XmlConfiguration.java:1182)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at org.eclipse.jetty.start.Main.invokeMain(Main.java:473)
at org.eclipse.jetty.start.Main.start(Main.java:615)
at org.eclipse.jetty.start.Main.main(Main.java:96)
java.lang.reflect.InvocationTargetException
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at org.eclipse.jetty.start.Main.invokeMain(Main.java:473)
at org.eclipse.jetty.start.Main.start(Main.java:615)
at org.eclipse.jetty.start.Main.main(Main.java:96)
Caused by: MultiException[java.io.FileNotFoundException: /opt/zimbra/jetty-distribution-7.6.12.v20130726/webapps/zimbra/WEB-INF/web.xml (Permission denied), java.io.FileNotFoundException: /opt/zimbra/jetty-distribution-7.6.12.v20130726/webapps/zimbraAdmin/WEB-INF/web.xml (Permission denied), java.io.FileNotFoundException: /opt/zimbra/jetty-distribution-7.6.12.v20130726/webapps/zimlet/WEB-INF/web.xml (Permission denied), java.io.IOException: Cannot write log directory /opt/zimbra/log]
at org.eclipse.jetty.server.Server.doStart(Server.java:274)
at org.mortbay.setuid.SetUIDServer.doStart(SetUIDServer.java:178)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:64)
at org.eclipse.jetty.xml.XmlConfiguration$1.run(XmlConfiguration.java:1259)
at java.security.AccessController.doPrivileged(Native Method)
at org.eclipse.jetty.xml.XmlConfiguration.main(XmlConfiguration.java:1182)
... 7 more

Usage: java -jar start.jar [options] [properties] [configs]
java -jar start.jar --help # for more information
~




These are my zimbra.log

Mar 15 11:46:25 mail zimbramon[32450]: 32450:info: Starting services initiated by zmcontrol
Mar 15 11:46:25 mail slapd[1113]: @(#) $OpenLDAP: slapd 2.4.39 (Aug 11 2014 15:17:20) $#012#011build@zre-rhel7-64.eng.zimbra.com:/home/build/p4/zimbra/IRONMAIDEN/ThirdParty/openldap/openldap-2.4.39.2z/servers/slapd
Mar 15 11:46:25 mail slapd[1114]: slapd starting
Mar 15 11:46:32 mail zimbramon[32450]: 32450:info: Starting zmconfigd via zmcontrol
Mar 15 11:46:36 mail zmconfigd[1286]: zmconfigd started on mail.officebrain.com with loglevel=3 pid=1286
Mar 15 11:46:36 mail zmconfigd[1286]: Fetching All configs
Mar 15 11:46:36 mail zmconfigd[1286]: All configs fetched in 0.15 seconds
Mar 15 11:46:38 mail zmconfigd[1286]: Rewrote: /opt/zimbra/mailboxd/etc/jetty.xml with mode 440 (0.15 sec)
Mar 15 11:46:38 mail zmconfigd[1286]: Rewrote: /opt/zimbra/postfix/conf/master.cf with mode 440 (0.03 sec)
Mar 15 11:46:38 mail zmconfigd[1286]: Rewrote: /opt/zimbra/mailboxd/webapps/zimbraAdmin/WEB-INF/web.xml with mode 440 (0.03 sec)
Mar 15 11:46:38 mail zmconfigd[1286]: Rewrote: /opt/zimbra/mailboxd/webapps/zimbraAdmin/WEB-INF/jetty-env.xml with mode 440 (0.00 sec)
Mar 15 11:46:38 mail zmconfigd[1286]: Rewrote: /opt/zimbra/conf/opendkim.conf with mode 440 (0.01 sec)
Mar 15 11:46:38 mail zmconfigd[1286]: Rewrote: /opt/zimbra/conf/clamd.conf with mode 440 (0.02 sec)
Mar 15 11:46:38 mail zmconfigd[1286]: Rewrote: /opt/zimbra/conf/log4j.properties with mode 440 (0.03 sec)
Mar 15 11:46:38 mail zmconfigd[1286]: Rewrote: /opt/zimbra/conf/mta_milter_options with mode 440 (0.00 sec)
Mar 15 11:46:38 mail zmconfigd[1286]: Rewrote: /opt/zimbra/conf/opendkim-localnets.conf with mode 440 (0.00 sec)
Mar 15 11:46:38 mail zmconfigd[1286]: Rewrote: /opt/zimbra/conf/spnego_java_options with mode 440 (0.00 sec)
Mar 15 11:46:38 mail zmconfigd[1286]: Rewrote: /opt/zimbra/mailboxd/etc/jetty.properties with mode 440 (0.02 sec)
Mar 15 11:46:38 mail zmconfigd[1286]: Rewrote: /opt/zimbra/mailboxd/etc/krb5.ini with mode 440 (0.00 sec)
Mar 15 11:46:38 mail zmconfigd[1286]: Rewrote: /opt/zimbra/conf/dspam.conf with mode 440 (0.03 sec)
Mar 15 11:46:38 mail zmconfigd[1286]: Rewrote: /opt/zimbra/postfix/conf/tag_as_originating.re with mode 440 (0.00 sec)
Mar 15 11:46:38 mail zmconfigd[1286]: Rewrote: /opt/zimbra/conf/freshclam.conf with mode 600 (0.02 sec)
Mar 15 11:46:38 mail zmconfigd[1286]: Rewrote: /opt/zimbra/conf/stats.conf with mode 440 (0.01 sec)
Mar 15 11:46:38 mail zmconfigd[1286]: Rewrote: /opt/zimbra/conf/sasl2/smtpd.conf with mode 440 (0.00 sec)
Mar 15 11:46:38 mail zmconfigd[1286]: Rewrote: /opt/zimbra/postfix/conf/tag_as_foreign.re with mode 440 (0.00 sec)
Mar 15 11:46:38 mail zmconfigd[1286]: Rewrote: /opt/zimbra/mailboxd/webapps/zimbra/WEB-INF/web.xml with mode 440 (0.04 sec)
Mar 15 11:46:38 mail zmconfigd[1286]: Rewrote: /opt/zimbra/mailboxd/etc/spnego.properties with mode 440 (0.00 sec)
Mar 15 11:46:38 mail zmconfigd[1286]: Rewrote: /opt/zimbra/mailboxd/webapps/zimbra/WEB-INF/jetty-env.xml with mode 440 (0.00 sec)
Mar 15 11:46:38 mail zmconfigd[1286]: Rewrote: /opt/zimbra/conf/amavisd.conf with mode 440 (0.07 sec)
Mar 15 11:46:38 mail zmconfigd[1286]: Rewrote: /opt/zimbra/mailboxd/webapps/service/WEB-INF/web.xml with mode 440 (0.06 sec)
Mar 15 11:46:38 mail zmconfigd[1286]: Rewrote: /opt/zimbra/conf/postfix_header_checks with mode 440 (0.00 sec)
Mar 15 11:46:38 mail zmconfigd[1286]: Rewrote: /opt/zimbra/mailboxd/etc/spnego.conf with mode 440 (0.01 sec)
Mar 15 11:46:38 mail zmconfigd[1286]: Rewrote: /opt/zimbra/mailboxd/webapps/zimlet/WEB-INF/web.xml with mode 440 (0.01 sec)
Mar 15 11:46:38 mail zmconfigd[1286]: Rewrote: /opt/zimbra/conf/sa/salocal.cf with mode 440 (0.01 sec)
Mar 15 11:46:38 mail zmconfigd[1286]: Rewrote: /opt/zimbra/conf/saslauthd.conf with mode 440 (0.00 sec)
Mar 15 11:46:40 mail zmconfigd[1286]: All rewrite threads completed in 2.62 sec
Mar 15 11:46:40 mail zmconfigd[1286]: All restarts completed in 0.00 sec
Mar 15 11:46:40 mail zimbramon[32450]: 32450:info: Starting logger via zmcontrol
Mar 15 11:46:42 mail zimbramon[32450]: 32450:info: Starting mailbox via zmcontrol
Mar 15 11:46:46 mail zmmailboxdmgr[2529]: stale pid 29833 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process
Mar 15 11:46:46 mail zmmailboxdmgr[2529]: assuming no other instance is running
Mar 15 11:46:46 mail zmmailboxdmgr[2529]: stale pid 23559 found in /opt/zimbra/log/zmmailboxd.pid: No such process
Mar 15 11:46:46 mail zmmailboxdmgr[2529]: assuming no other instance is running
Mar 15 11:46:46 mail zmmailboxdmgr[2529]: no manager process is running
Mar 15 11:46:46 mail zmmailboxdmgr[2540]: start requested
Mar 15 11:46:46 mail zmmailboxdmgr[2540]: checking if another instance of manager is already running
Mar 15 11:46:46 mail zmmailboxdmgr[2540]: stale pid 29833 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process
Mar 15 11:46:46 mail zmmailboxdmgr[2540]: assuming no other instance is running
Mar 15 11:46:46 mail zmmailboxdmgr[2540]: stale pid 23559 found in /opt/zimbra/log/zmmailboxd.pid: No such process
Mar 15 11:46:46 mail zmmailboxdmgr[2540]: assuming no other instance is running
Mar 15 11:46:46 mail zmmailboxdmgr[2541]: wrote manager pid 2541 to /opt/zimbra/log/zmmailboxd_manager.pid
Mar 15 11:46:46 mail zmmailboxdmgr[2541]: manager started mailboxd/JVM with pid 2542
Mar 15 11:46:46 mail zmmailboxdmgr[2542]: wrote java pid 2542 to /opt/zimbra/log/zmmailboxd_java.pid
Mar 15 11:46:47 mail zmmailboxdmgr[2541]: manager woke up from wait on mailboxd/JVM with pid 2542
Mar 15 11:46:47 mail zmmailboxdmgr[2541]: manager started mailboxd/JVM with pid 2671
Mar 15 11:46:47 mail zmmailboxdmgr[2671]: wrote java pid 2671 to /opt/zimbra/log/zmmailboxd_java.pid
Mar 15 11:46:48 mail zmmailboxdmgr[2541]: manager woke up from wait on mailboxd/JVM with pid 2671
Mar 15 11:46:48 mail zmmailboxdmgr[2541]: mailboxd/JVM exited twice in 1 seconds (tolerance=60)
Mar 15 11:47:40 mail zmconfigd[1286]: Fetching All configs
Mar 15 11:47:40 mail zmconfigd[1286]: All configs fetched in 0.08 seconds
Mar 15 11:47:40 mail zmconfigd[1286]: Executed: /opt/zimbra/bin/zmamavisdctl status norewrite returned 1 (24 - 13) (0.04 sec)
Mar 15 11:47:40 mail zmconfigd[1286]: Tracking service amavis
Mar 15 11:47:40 mail zmconfigd[1286]: Executed: /opt/zimbra/bin/zmamavisdctl status norewrite returned 1 (24 - 13) (0.03 sec)
Mar 15 11:47:40 mail zmconfigd[1286]: Tracking service antispam
Mar 15 11:47:40 mail zmconfigd[1286]: Executed: /opt/zimbra/bin/zmclamdctl status norewrite returned 1 (23 - 13) (0.02 sec)
Mar 15 11:47:40 mail zmconfigd[1286]: Tracking service antivirus
Mar 15 11:47:40 mail zmconfigd[1286]: Tracking service ldap
Mar 15 11:47:40 mail zmconfigd[1286]: Watchdog: service ldap now available for watchdog.
Mar 15 11:47:40 mail zmconfigd[1286]: Tracking service logger
Mar 15 11:47:40 mail zmconfigd[1286]: Watchdog: service logger now available for watchdog.
Mar 15 11:47:41 mail zmmailboxdmgr[2833]: stale pid 2541 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process
Mar 15 11:47:41 mail zmmailboxdmgr[2833]: assuming no other instance is running
Mar 15 11:47:41 mail zmmailboxdmgr[2833]: stale pid 23559 found in /opt/zimbra/log/zmmailboxd.pid: No such process
Mar 15 11:47:41 mail zmmailboxdmgr[2833]: assuming no other instance is running
Mar 15 11:47:41 mail zmmailboxdmgr[2833]: no manager process is running
Mar 15 11:47:41 mail zmconfigd[1286]: Executed: /opt/zimbra/bin/zmstorectl status norewrite returned 1 (30 - 13) (0.04 sec)
Mar 15 11:47:41 mail zmconfigd[1286]: Tracking service mailbox
Mar 15 11:47:41 mail zmmailboxdmgr[2858]: stale pid 2541 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process
Mar 15 11:47:41 mail zmmailboxdmgr[2858]: assuming no other instance is running
Mar 15 11:47:41 mail zmmailboxdmgr[2858]: stale pid 23559 found in /opt/zimbra/log/zmmailboxd.pid: No such process
Mar 15 11:47:41 mail zmmailboxdmgr[2858]: assuming no other instance is running
Mar 15 11:47:41 mail zmmailboxdmgr[2858]: no manager process is running
Mar 15 11:47:41 mail zmconfigd[1286]: Executed: /opt/zimbra/bin/zmmailboxdctl status norewrite returned 1 (25 - 13) (0.03 sec)
Mar 15 11:47:41 mail zmconfigd[1286]: Tracking service mailboxd
Mar 15 11:47:41 mail zmconfigd[1286]: Executed: /opt/zimbra/bin/zmmemcachedctl status norewrite returned 1 (26 - 13) (0.01 sec)
Mar 15 11:47:41 mail zmconfigd[1286]: Tracking service memcached
Mar 15 11:47:41 mail zmconfigd[1286]: Executed: /opt/zimbra/bin/zmmtactl status norewrite returned 1 (53 - 13) (0.03 sec)
Mar 15 11:47:41 mail zmconfigd[1286]: Tracking service mta
Mar 15 11:47:41 mail zmconfigd[1286]: Executed: /opt/zimbra/bin/zmopendkimctl status norewrite returned 1 (25 - 13) (0.01 sec)
Mar 15 11:47:41 mail zmconfigd[1286]: Tracking service opendkim
Mar 15 11:47:41 mail zmconfigd[1286]: Executed: /opt/zimbra/bin/zmproxyctl status norewrite returned 1 (26 - 13) (0.01 sec)
Mar 15 11:47:41 mail zmconfigd[1286]: Tracking service proxy
Mar 15 11:47:41 mail zmconfigd[1286]: Executed: /opt/zimbra/bin/zmsaslauthdctl status norewrite returned 1 (26 - 13) (0.01 sec)
Mar 15 11:47:41 mail zmconfigd[1286]: Tracking service sasl
Mar 15 11:47:41 mail zmconfigd[1286]: Executed: /opt/zimbra/bin/zmswatchctl status norewrite returned 1 (25 - 13) (0.01 sec)
Mar 15 11:47:41 mail zmconfigd[1286]: Tracking service snmp
Mar 15 11:47:41 mail zmconfigd[1286]: Executed: /opt/zimbra/bin/zmspellctl status norewrite returned 1 (27 - 13) (0.00 sec)
Mar 15 11:47:41 mail zmconfigd[1286]: Tracking service spell
Mar 15 11:47:41 mail zmconfigd[1286]: Executed: /opt/zimbra/bin/zmstatctl status norewrite returned 1 (14 - 13) (0.24 sec)
Mar 15 11:47:41 mail zmconfigd[1286]: Tracking service stats
Mar 15 11:47:41 mail zmconfigd[1286]: Watchdog: skipping service antivirus. Service not yet available for restarts.
Mar 15 11:47:41 mail zmconfigd[1286]: All rewrite threads completed in 0.00 sec
Mar 15 11:47:41 mail zmconfigd[1286]: All restarts completed in 0.00 sec
Mar 15 11:47:48 mail zimbramon[32450]: 32450:info: Starting memcached via zmcontrol
Mar 15 11:47:49 mail zimbramon[32450]: 32450:info: Starting proxy via zmcontrol
Mar 15 11:47:49 mail zimbramon[32450]: 32450:info: Starting amavis via zmcontrol
Mar 15 11:47:50 mail amavis[3017]: starting. /opt/zimbra/amavisd/sbin/amavisd at mail.officebrain.com amavisd-new-2.8.0 (20120630), Unicode aware, LC_ALL="C", LANG="C"
Mar 15 11:47:50 mail amavis[3017]: perl=5.016003, user=996, EUID: 996 (996); group=, EGID: 994 4 5 89 994 (994 4 5 89 994)
Mar 15 11:47:50 mail amavis[3017]: SpamControl: init_pre_chroot on SpamAssassin done
Mar 15 11:47:50 mail amavis[3022]: Net::Server: Process Backgrounded
Mar 15 11:47:50 mail amavis[3022]: Net::Server: 2018/03/15-11:47:50 Amavis (type Net::Server::PreForkSimple) starting! pid(3022)
Mar 15 11:47:50 mail amavis[3022]: Net::Server: Binding to UNIX socket file "/opt/zimbra/data/amavisd/amavisd.sock"
Mar 15 11:47:50 mail amavis[3022]: Net::Server: Binding to TCP port 10024 on host 127.0.0.1 with IPv4
Mar 15 11:47:50 mail amavis[3022]: Net::Server: Binding to TCP port 10024 on host ::1 with IPv6
Mar 15 11:47:50 mail amavis[3022]: Net::Server: Binding to TCP port 10026 on host 127.0.0.1 with IPv4
Mar 15 11:47:50 mail amavis[3022]: Net::Server: Binding to TCP port 10026 on host ::1 with IPv6
Mar 15 11:47:50 mail amavis[3022]: Net::Server: Binding to TCP port 10032 on host 127.0.0.1 with IPv4
Mar 15 11:47:50 mail amavis[3022]: Net::Server: Binding to TCP port 10032 on host ::1 with IPv6
Mar 15 11:47:50 mail amavis[3022]: Net::Server: Group Not Defined. Defaulting to EGID '994 4 5 89 994'
Mar 15 11:47:50 mail amavis[3022]: Net::Server: User Not Defined. Defaulting to EUID '996'
Mar 15 11:47:50 mail amavis[3022]: Module Amavis::Conf 2.316
Mar 15 11:47:50 mail amavis[3022]: Module Archive::Zip 1.37
Mar 15 11:47:50 mail amavis[3022]: Module Compress::Zlib 2.064
Mar 15 11:47:50 mail amavis[3022]: Module Convert::TNEF 0.18
Mar 15 11:47:50 mail amavis[3022]: Module Convert::UUlib 1.4
Mar 15 11:47:50 mail amavis[3022]: Module Crypt::OpenSSL::RSA 0.27
Mar 15 11:47:50 mail amavis[3022]: Module DB_File 1.831
Mar 15 11:47:50 mail amavis[3022]: Module Digest::MD5 2.52
Mar 15 11:47:50 mail amavis[3022]: Module Digest::SHA 5.86
Mar 15 11:47:50 mail amavis[3022]: Module Encode 2.51
Mar 15 11:47:50 mail amavis[3022]: Module File::Temp 0.2304
Mar 15 11:47:50 mail amavis[3022]: Module IO::Socket::INET6 2.72
Mar 15 11:47:50 mail amavis[3022]: Module IO::Socket::IP 0.28
Mar 15 11:47:50 mail amavis[3022]: Module MIME::Entity 5.505
Mar 15 11:47:50 mail amavis[3022]: Module MIME::Parser 5.505
Mar 15 11:47:50 mail amavis[3022]: Module MIME::Tools 5.505
Mar 15 11:47:50 mail amavis[3022]: Module Mail::DKIM::Signer 0.4
Mar 15 11:47:50 mail amavis[3022]: Module Mail::DKIM::Verifier 0.4
Mar 15 11:47:50 mail amavis[3022]: Module Mail::Header 2.13
Mar 15 11:47:50 mail amavis[3022]: Module Mail::Internet 2.13
Mar 15 11:47:50 mail amavis[3022]: Module Mail::SPF v2.009
Mar 15 11:47:50 mail amavis[3022]: Module Mail::SpamAssassin 3.004000
Mar 15 11:47:50 mail amavis[3022]: Module Net::DNS 0.74
Mar 15 11:47:50 mail amavis[3022]: Module Net::LDAP 0.53
Mar 15 11:47:50 mail amavis[3022]: Module Net::Server 2.007
Mar 15 11:47:50 mail amavis[3022]: Module NetAddr::IP 4.072
Mar 15 11:47:50 mail amavis[3022]: Module Scalar::Util 1.38
Mar 15 11:47:50 mail amavis[3022]: Module Socket 2.013
Mar 15 11:47:50 mail amavis[3022]: Module Socket6 0.25
Mar 15 11:47:50 mail amavis[3022]: Module Time::HiRes 1.9726
Mar 15 11:47:50 mail amavis[3022]: Module URI 1.60
Mar 15 11:47:50 mail amavis[3022]: Module Unix::Syslog 1.1
Mar 15 11:47:50 mail amavis[3022]: Amavis::ZMQ code NOT loaded
Mar 15 11:47:50 mail amavis[3022]: Amavis::DB code NOT loaded
Mar 15 11:47:50 mail amavis[3022]: SQL base code NOT loaded
Mar 15 11:47:50 mail amavis[3022]: SQL::Log code NOT loaded
Mar 15 11:47:50 mail amavis[3022]: SQL::Quarantine NOT loaded
Mar 15 11:47:50 mail amavis[3022]: Lookup::SQL code NOT loaded
Mar 15 11:47:50 mail amavis[3022]: Lookup::LDAP code loaded
Mar 15 11:47:50 mail amavis[3022]: AM.PDP-in proto code loaded
Mar 15 11:47:50 mail amavis[3022]: SMTP-in proto code loaded
Mar 15 11:47:50 mail amavis[3022]: Courier proto code NOT loaded
Mar 15 11:47:50 mail amavis[3022]: SMTP-out proto code loaded
Mar 15 11:47:50 mail amavis[3022]: Pipe-out proto code NOT loaded
Mar 15 11:47:50 mail amavis[3022]: BSMTP-out proto code NOT loaded
Mar 15 11:47:50 mail amavis[3022]: Local-out proto code loaded
Mar 15 11:47:50 mail amavis[3022]: OS_Fingerprint code NOT loaded
Mar 15 11:47:50 mail amavis[3022]: ANTI-VIRUS code loaded
Mar 15 11:47:50 mail amavis[3022]: ANTI-SPAM code loaded
Mar 15 11:47:50 mail amavis[3022]: ANTI-SPAM-EXT code NOT loaded
Mar 15 11:47:50 mail amavis[3022]: ANTI-SPAM-C code NOT loaded
Mar 15 11:47:50 mail amavis[3022]: ANTI-SPAM-SA code loaded
Mar 15 11:47:50 mail amavis[3022]: Unpackers code loaded
Mar 15 11:47:50 mail amavis[3022]: DKIM code loaded
Mar 15 11:47:50 mail amavis[3022]: Tools code NOT loaded
Mar 15 11:47:50 mail amavis[3022]: Found $file at /usr/bin/file
Mar 15 11:47:50 mail amavis[3022]: No $altermime, not using it
Mar 15 11:47:50 mail amavis[3022]: Internal decoder for .mail
Mar 15 11:47:50 mail amavis[3022]: Internal decoder for .asc
Mar 15 11:47:50 mail amavis[3022]: Internal decoder for .uue
Mar 15 11:47:50 mail amavis[3022]: Internal decoder for .hqx
Mar 15 11:47:50 mail amavis[3022]: Internal decoder for .ync
Mar 15 11:47:50 mail amavis[3022]: No ext program for .F, tried: unfreeze, freeze -d, melt, fcat
Mar 15 11:47:50 mail amavis[3022]: Found decoder for .Z at /usr/bin/gzip -d
Mar 15 11:47:50 mail amavis[3022]: Found decoder for .gz at /usr/bin/gzip -d
Mar 15 11:47:50 mail amavis[3022]: No ext program for .bz2, tried: bzip2 -d
Mar 15 11:47:50 mail amavis[3022]: Found decoder for .xz at /usr/bin/xzdec
Mar 15 11:47:50 mail amavis[3022]: Found decoder for .lzma at /usr/bin/xz -dc --format=lzma
Mar 15 11:47:50 mail amavis[3022]: No ext program for .lrz, tried: lrzip -q -k -d -o -, lrzcat -q -k
Mar 15 11:47:50 mail amavis[3022]: No ext program for .lzo, tried: lzop -d
Mar 15 11:47:50 mail amavis[3022]: Found decoder for .rpm at /usr/bin/rpm2cpio
Mar 15 11:47:50 mail amavis[3022]: Found decoder for .cpio at /usr/bin/cpio
Mar 15 11:47:50 mail amavis[3022]: Found decoder for .tar at /usr/bin/cpio
Mar 15 11:47:50 mail amavis[3022]: Found decoder for .deb at /usr/bin/ar
Mar 15 11:47:50 mail amavis[3022]: No ext program for .rar, tried: unrar, rar
Mar 15 11:47:50 mail amavis[3022]: No ext program for .arj, tried: unarj, arj
Mar 15 11:47:50 mail amavis[3022]: No ext program for .arc, tried: nomarch, arc
Mar 15 11:47:50 mail amavis[3022]: No ext program for .zoo, tried: zoo, unzoo
Mar 15 11:47:50 mail amavis[3022]: No ext program for .doc, tried: ripole
Mar 15 11:47:50 mail amavis[3022]: No ext program for .cab, tried: cabextract
Mar 15 11:47:50 mail amavis[3022]: No ext program for .tnef, tried: tnef
Mar 15 11:47:50 mail amavis[3022]: Internal decoder for .tnef
Mar 15 11:47:50 mail amavis[3022]: No ext program for .zip, tried: 7za, 7z
Mar 15 11:47:50 mail amavis[3022]: No ext program for .kmz, tried: 7za, 7z
Mar 15 11:47:50 mail amavis[3022]: Internal decoder for .zip
Mar 15 11:47:50 mail amavis[3022]: Internal decoder for .kmz
Mar 15 11:47:50 mail amavis[3022]: No ext program for .7z, tried: 7zr, 7za, 7z
Mar 15 11:47:50 mail amavis[3022]: No ext program for .7z, tried: 7za, 7z
Mar 15 11:47:50 mail amavis[3022]: No ext program for .bz2, tried: 7za, 7z
Mar 15 11:47:50 mail amavis[3022]: No ext program for .jar, tried: 7z
Mar 15 11:47:50 mail amavis[3022]: No ext program for .arj, tried: 7z
Mar 15 11:47:50 mail amavis[3022]: No ext program for .rar, tried: 7z
Mar 15 11:47:50 mail amavis[3022]: No ext program for .swf, tried: 7z
Mar 15 11:47:50 mail amavis[3022]: No ext program for .lha, tried: 7z
Mar 15 11:47:50 mail amavis[3022]: No ext program for .iso, tried: 7z
Mar 15 11:47:50 mail amavis[3022]: No ext program for .cab, tried: 7z
Mar 15 11:47:50 mail amavis[3022]: No ext program for .exe, tried: unrar, rar; lha; unarj, arj
Mar 15 11:47:50 mail amavis[3022]: No decoder for .7z
Mar 15 11:47:50 mail amavis[3022]: No decoder for .F
Mar 15 11:47:50 mail amavis[3022]: No decoder for .arc
Mar 15 11:47:50 mail amavis[3022]: No decoder for .arj
Mar 15 11:47:50 mail amavis[3022]: No decoder for .bz2
Mar 15 11:47:50 mail amavis[3022]: No decoder for .cab
Mar 15 11:47:50 mail amavis[3022]: No decoder for .doc
Mar 15 11:47:50 mail amavis[3022]: No decoder for .exe
Mar 15 11:47:50 mail amavis[3022]: No decoder for .iso
Mar 15 11:47:50 mail amavis[3022]: No decoder for .jar
Mar 15 11:47:50 mail amavis[3022]: No decoder for .lha
Mar 15 11:47:50 mail amavis[3022]: No decoder for .lrz
Mar 15 11:47:50 mail amavis[3022]: No decoder for .lzo
Mar 15 11:47:50 mail amavis[3022]: No decoder for .rar
Mar 15 11:47:50 mail amavis[3022]: No decoder for .swf
Mar 15 11:47:50 mail amavis[3022]: No decoder for .zoo
Mar 15 11:47:50 mail amavis[3022]: Using primary internal av scanner code for ClamAV-clamd
Mar 15 11:47:50 mail amavis[3022]: initializing Mail::SpamAssassin (0)
Mar 15 11:47:50 mail amavis[3022]: SpamControl: init_pre_fork on SpamAssassin done
Mar 15 11:47:50 mail amavis[3022]: extra modules loaded after daemonizing/chrooting: /opt/zimbra/zimbramon/lib/Net/libnet.cfg, Mail/SpamAssassin/Plugin/FreeMail.pm, Mail/SpamAssassin/Plugin/SpamCop.pm, Net/Cmd.pm, Net/Config.pm, Net/DNS/RR/OPT.pm, Net/SMTP.pm
Mar 15 11:47:51 mail zimbramon[32450]: 32450:info: Starting antispam via zmcontrol
Mar 15 11:47:51 mail zimbramon[32450]: 32450:info: Starting antivirus via zmcontrol
Mar 15 11:47:51 mail clamd[3165]: clamd daemon 0.98.4 (OS: linux-gnu, ARCH: x86_64, CPU: x86_64)
Mar 15 11:47:51 mail clamd[3165]: Log file size limited to 20971520 bytes.
Mar 15 11:47:51 mail clamd[3165]: Reading databases from /opt/zimbra/data/clamav/db
Mar 15 11:47:51 mail clamd[3165]: Not loading PUA signatures.
Mar 15 11:47:51 mail clamd[3165]: Bytecode: Security mode set to "TrustSigned".
Mar 15 11:47:56 mail clamd[3165]: Loaded 4566322 signatures.
Mar 15 11:47:57 mail clamd[3165]: LOCAL: Unix socket file /opt/zimbra/data/clamav/clamav.sock
Mar 15 11:47:57 mail clamd[3165]: LOCAL: Setting connection queue length to 200
Mar 15 11:47:57 mail clamd[3168]: Limits: Global size limit set to 62914560 bytes.
Mar 15 11:47:57 mail clamd[3168]: Limits: File size limit set to 62914560 bytes.
Mar 15 11:47:57 mail clamd[3168]: Limits: Recursion level limit set to 16.
Mar 15 11:47:57 mail clamd[3168]: Limits: Files limit set to 10000.
Mar 15 11:47:57 mail clamd[3168]: Limits: MaxEmbeddedPE limit set to 10485760 bytes.
Mar 15 11:47:57 mail clamd[3168]: Limits: MaxHTMLNormalize limit set to 10485760 bytes.
Mar 15 11:47:57 mail clamd[3168]: Limits: MaxHTMLNoTags limit set to 2097152 bytes.
Mar 15 11:47:57 mail clamd[3168]: Limits: MaxScriptNormalize limit set to 5242880 bytes.
Mar 15 11:47:57 mail clamd[3168]: Limits: MaxZipTypeRcg limit set to 1048576 bytes.
Mar 15 11:47:57 mail clamd[3168]: Limits: MaxPartitions limit set to 50.
Mar 15 11:47:57 mail clamd[3168]: Limits: MaxIconsPE limit set to 100.
Mar 15 11:47:57 mail clamd[3168]: Archive support enabled.
Mar 15 11:47:57 mail clamd[3168]: Archive: Blocking encrypted archives.
Mar 15 11:47:57 mail clamd[3168]: Algorithmic detection enabled.
Mar 15 11:47:57 mail clamd[3168]: Portable Executable support enabled.
Mar 15 11:47:57 mail clamd[3168]: ELF support enabled.
Mar 15 11:47:57 mail clamd[3168]: Mail files support enabled.
Mar 15 11:47:57 mail clamd[3168]: OLE2 support enabled.
Mar 15 11:47:57 mail clamd[3168]: PDF support enabled.
Mar 15 11:47:57 mail clamd[3168]: SWF support enabled.
Mar 15 11:47:57 mail clamd[3168]: HTML support enabled.
Mar 15 11:47:57 mail clamd[3168]: Self checking every 600 seconds.
Mar 15 11:48:01 mail zimbramon[32450]: 32450:info: Starting opendkim via zmcontrol
Mar 15 11:48:01 mail opendkim[3199]: OpenDKIM Filter v2.9.2 starting (args: -x /opt/zimbra/conf/opendkim.conf -u zimbra)
Mar 15 11:48:03 mail zimbramon[32450]: 32450:info: Starting snmp via zmcontrol
Mar 15 11:48:03 mail zimbramon[32450]: 32450:info: Starting spell via zmcontrol
Mar 15 11:48:03 mail zimbramon[32450]: 32450:info: Starting mta via zmcontrol
Mar 15 11:48:03 mail saslauthd[3511]: detach_tty : master pid is: 3511
Mar 15 11:48:03 mail saslauthd[3511]: ipc_init : listening on socket: /opt/zimbra/data/sasl2/state/mux
Mar 15 11:48:03 mail /postfix-script[3639]: starting the Postfix mail system
Mar 15 11:48:03 mail postfix/master[3641]: daemon started -- version 2.10.3, configuration /opt/zimbra/postfix-2.10.3.2z/conf
Mar 15 11:48:03 mail zimbramon[32450]: 32450:info: Starting stats via zmcontrol
Mar 15 11:48:03 mail postfix/qmgr[3644]: 147E540054E36: from=<zimbra@mail.officebrain.com>, size=1119, nrcpt=1 (queue active)
Mar 15 11:48:03 mail zmmailboxdmgr[3896]: stale pid 2541 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process
Mar 15 11:48:03 mail zmmailboxdmgr[3896]: assuming no other instance is running
Mar 15 11:48:03 mail zmmailboxdmgr[3896]: stale pid 23559 found in /opt/zimbra/log/zmmailboxd.pid: No such process
Mar 15 11:48:03 mail zmmailboxdmgr[3896]: assuming no other instance is running
Mar 15 11:48:03 mail zmmailboxdmgr[3896]: no manager process is running


Can any one help me out.
Post Reply