Zimbra

zibra · #1 (**permalink**) 03-18-2007, 10:59 PM

Dear all,
I can't start my zimbra. I check in logs (/var/log/zimbra.log) and see:

Mar 19 12:53:32 zimbra zimbramon[3931]: 3931:info: Starting services
Mar 19 12:53:32 zimbra slapd[4037]: @(#) $OpenLDAP: slapd 2.3.21 (Feb 9 2007 14:47:10) $ build@build.liquidsys.com:/home/build/p4/main/ThirdParty/openldap/openldap-2.3.21/servers/slapd
Mar 19 12:54:01 zimbra crond(pam_unix)[4149]: session opened for user zimbra by (uid=0)
Mar 19 12:54:02 zimbra zimbramon[4150]: 4150:info: 2007-03-19 12:54:01, STATUS: : Cannot: determine
Mar 19 12:54:02 zimbra crond(pam_unix)[4149]: session closed for user zimbra
Mar 19 12:55:01 zimbra crond(pam_unix)[4298]: session opened for user mailman by (uid=0)
Mar 19 12:55:03 zimbra crond(pam_unix)[4298]: session closed for user mailman

Mar 19 12:56:01 zimbra crond(pam_unix)[4417]: session opened for user zimbra by (uid=0)
Mar 19 12:56:02 zimbra zimbramon[4418]: 4418:info: 2007-03-19 12:56:01, STATUS: : Cannot: determine
Mar 19 12:56:02 zimbra crond(pam_unix)[4417]: session closed for user zimbra
Mar 19 12:58:01 zimbra crond(pam_unix)[4685]: session opened for user zimbra by (uid=0)
Mar 19 12:58:02 zimbra zimbramon[4686]: 4686:info: 2007-03-19 12:58:01, STATUS: : Cannot: determine
Mar 19 12:58:02 zimbra crond(pam_unix)[4685]: session closed for user zimbra
Mar 19 13:00:01 zimbra crond(pam_unix)[4952]: session opened for user zimbra by (uid=0)
Mar 19 13:00:01 zimbra crond(pam_unix)[4954]: session opened for user zimbra by (uid=0)
Mar 19 13:00:01 zimbra crond(pam_unix)[4957]: session opened for user zimbra by (uid=0)
Mar 19 13:00:01 zimbra crond(pam_unix)[4959]: session opened for user zimbra by (uid=0)
Mar 19 13:00:01 zimbra crond(pam_unix)[4961]: session opened for user mailman by (uid=0)
Mar 19 13:00:01 zimbra crond(pam_unix)[4961]: session closed for user mailman
Mar 19 13:00:02 zimbra zimbramon[4958]: 4958:info: 2007-03-19 13:00:01, DISK: zimbra.net18.com: dev: /dev/hda3, mp: /, tot: 36452, avail: 6628
Mar 19 13:00:02 zimbra zimbramon[4958]: 4958:info: 2007-03-19 13:00:01, DISK: zimbra.net18.com: dev: /dev/hda1, mp: /boot, tot: 99, avail: 86
Mar 19 13:00:02 zimbra crond(pam_unix)[4957]: session closed for user zimbra
Mar 19 06:00:02 zimbra postfix/postqueue[4967]: fatal: Queue report unavailable - mail system is down
Mar 19 13:00:02 zimbra sendmail[5022]: NOQUEUE: SYSERR(zimbra): can not chdir(/var/spool/clientmqueue/): Permission denied
Mar 19 13:00:03 zimbra zimbramon[4960]: 4960:info: 2007-03-19 13:00:01, STATUS: : Cannot: determine
Mar 19 13:00:03 zimbra crond(pam_unix)[4959]: session closed for user zimbra
Mar 19 13:00:03 zimbra zimbramon[4953]: 4953:info: 2007-03-19 13:00:01, QUEUE: 0 0
Mar 19 13:00:03 zimbra crond(pam_unix)[4954]: session closed for user zimbra
Mar 19 13:01:01 zimbra crond(pam_unix)[5153]: session opened for user root by (uid=0)
Mar 19 13:01:01 zimbra crond(pam_unix)[5153]: session closed for user root
Mar 19 13:02:01 zimbra crond(pam_unix)[5275]: session opened for user zimbra by (uid=0)
Mar 19 13:02:02 zimbra zimbramon[5276]: 5276:info: 2007-03-19 13:02:01, STATUS: : Cannot: determine
Mar 19 13:02:02 zimbra crond(pam_unix)[5275]: session closed for user zimbra
Mar 19 13:04:01 zimbra crond(pam_unix)[5542]: session opened for user zimbra by (uid=0)
Mar 19 13:04:02 zimbra zimbramon[5543]: 5543:info: 2007-03-19 13:04:01, STATUS: : Cannot: determine
Mar 19 13:04:02 zimbra crond(pam_unix)[5542]: session closed for user zimbra

...

I'm using 4.5.3 OSS Edition. I don't understand what is reason. Only LDAP is started.

Please help me.

Thanks.

jholder · #2 (**permalink**) 03-18-2007, 11:04 PM

Hmm
Let's try this
su - zimbra
zmcontrol stop
su - root
ps auww | grep zimbra

Kill any left over processes.
Then
su - zimbra
zmcontrol start

tail -200 /var/log/zimbra.log
Post that
and
tail -200 /opt/zimbra/log/mailbox.log
Post that

zibra · #3 (**permalink**) 03-18-2007, 11:28 PM

Hi,
This is my log files.

/opt/zimbra/log/mailbox.log

2007-03-17 14:50:14,864 INFO [SIGTERM handler] [] TcpServer/7025 - LmtpServer shutting down idle thread pool
2007-03-17 14:50:14,864 INFO [SIGTERM handler] [] TcpServer/110 - Pop3Server initiating shutdown
2007-03-17 14:50:14,864 INFO [Pop3Server] [] TcpServer/110 - finished accept loop
2007-03-17 14:50:14,864 INFO [SIGTERM handler] [] TcpServer/110 - Pop3Server shutting down idle thread pool
2007-03-17 14:50:14,865 INFO [SIGTERM handler] [] TcpServer/995 - Pop3Server initiating shutdown
2007-03-17 14:50:14,865 INFO [Pop3Server] [] TcpServer/995 - finished accept loop
2007-03-17 14:50:14,865 INFO [SIGTERM handler] [] TcpServer/995 - Pop3Server shutting down idle thread pool
2007-03-17 14:50:14,865 INFO [SIGTERM handler] [] TcpServer/143 - ImapServer initiating shutdown
2007-03-17 14:50:14,865 INFO [ImapServer] [] TcpServer/143 - finished accept loop
2007-03-17 14:50:14,865 INFO [SIGTERM handler] [] TcpServer/143 - ImapServer shutting down idle thread pool
2007-03-17 14:50:14,865 INFO [SIGTERM handler] [] TcpServer/993 - ImapSSLServer initiating shutdown
2007-03-17 14:50:14,865 INFO [ImapSSLServer] [] TcpServer/993 - finished accept loop
2007-03-17 14:50:14,865 INFO [SIGTERM handler] [] TcpServer/993 - ImapSSLServer shutting down idle thread pool
2007-03-17 14:50:14,882 INFO [NOTIFY-7035-Server] [] misc - spurious wakeup [1] [no attachment] interest=ACCEPT ready= key=1a6518 poolSize=0 queueSize=0
2007-03-17 14:50:14,882 INFO [NOTIFY-7035-Server] [] misc - shutting down thread pool
2007-03-17 14:50:14,882 INFO [NOTIFY-7035-Server] [] misc - waiting for thread pool to shutdown
2007-03-17 14:50:14,882 INFO [NOTIFY-7035-Server] [] misc - done waiting for thread pool to shutdown
2007-03-17 14:50:14,882 INFO [NOTIFY-7035-Server] [] misc - closing all selection keys
2007-03-17 14:50:14,883 INFO [NOTIFY-7035-Server] [] misc - closed selector
2007-03-17 14:50:14,884 INFO [NOTIFY-7035-Server] [] misc - ended server loop
2007-03-17 14:50:14,898 INFO [SIGTERM handler] [] session - shutdown: clearing SessionCache
2007-03-17 14:50:14,898 INFO [IndexWritersSweeper] [] MailboxIndex - IndexWritersSweeper thread exiting
2007-03-17 14:50:14,898 INFO [SIGTERM handler] [] MailboxIndex - Flushing all open index writers
2007-03-17 14:50:14,964 INFO [SIGTERM handler] [] RedoLogManager - Logged: 0 items, 0.0ms/item
2007-03-17 14:50:14,969 INFO [IncomingDirectorySweeper] [] FileBlobStore - IncomingDirectorySweeper thread starting
2007-03-17 14:50:14,969 INFO [IncomingDirectorySweeper] [] FileBlobStore - IncomingDirectorySweeper thread exiting
2007-03-17 14:50:14,969 INFO [SIGTERM handler] [] extensions - Destroying extensions
2007-03-17 14:50:15,014 INFO [SIGTERM handler] [] extensions - Destroyed extension clamscanner: com.zimbra.clam.ClamScanner@com.zimb...tensio n.ZimbraExtensionClassLoader@10ebe18
2007-03-17 14:50:15,015 INFO [SIGTERM handler] [] ContentServlet - Servlet ContentServlet shutting down
2007-03-17 14:50:15,015 INFO [SIGTERM handler] [] PublicICalServlet - Servlet PublicICalServlet shutting down
2007-03-17 14:50:15,016 INFO [SIGTERM handler] [] soap - Servlet SoapServlet shutting down
2007-03-17 14:50:15,016 INFO [SIGTERM handler] [] account - Servlet PreAuthServlet shutting down
2007-03-17 14:50:15,016 INFO [SIGTERM handler] [] mailbox - Servlet UserServlet shutting down
2007-03-17 14:50:15,017 INFO [SIGTERM handler] [] FileUploadServlet - Servlet FileUploadServlet shutting down
2007-03-17 14:53:42,279 INFO [main] [] soap - Servlet SoapServlet starting up
2007-03-17 14:53:42,343 INFO [main] [] soap - Adding service AccountService to SoapServlet
2007-03-17 14:53:42,434 INFO [main] [] soap - Adding service MailService to SoapServlet
2007-03-17 14:53:42,611 INFO [main] [] soap - Adding service IMService to SoapServlet
2007-03-17 14:53:42,616 INFO [main] [] misc - version=4.5.3_GA_733.RHEL4 release=20070228110021 builddate=20070228-1101 buildhost=build.liquidsys.com
2007-03-17 14:53:42,617 INFO [main] [] misc - LANG environment is set to: en_US.UTF-8
2007-03-17 14:53:42,638 INFO [main] [] DbPool - Setting mysql connector property: maxActive=10
2007-03-17 14:53:43,175 WARN [main] [] misc - LC.get(debug_calendar_force_utc) was null, returning empty string
2007-03-17 14:53:43,175 WARN [main] [] misc - LC.get(debug_validate_outgoing_icalendar) was null, returning empty string
2007-03-17 14:53:43,175 WARN [main] [] misc - LC.get(debug_disable_conversation) was null, returning empty string
2007-03-17 14:53:43,175 WARN [main] [] misc - LC.get(debug_disable_filter) was null, returning empty string
2007-03-17 14:53:43,175 WARN [main] [] misc - LC.get(debug_disable_message_analysis) was null, returning empty string
2007-03-17 14:53:43,175 WARN [main] [] misc - LC.get(debug_disable_mime_part_extraction) was null, returning empty string
2007-03-17 14:53:43,176 WARN [main] [] misc - LC.get(debug_disable_indexing) was null, returning empty string
2007-03-17 14:53:43,176 WARN [main] [] misc - LC.get(debug_disable_objects) was null, returning empty string
2007-03-17 14:53:43,176 WARN [main] [] misc - LC.get(debug_disable_indexing_attachments_separate ly) was null, returning empty string
2007-03-17 14:53:43,176 WARN [main] [] misc - LC.get(debug_disable_indexing_attachments_together ) was null, returning empty string
2007-03-17 14:53:43,176 WARN [main] [] misc - LC.get(debug_disable_mailbox_group) was null, returning empty string
2007-03-17 14:53:43,198 INFO [main] [] extensions - Loading extensions from /opt/zimbra/lib/ext
2007-03-17 14:53:43,213 INFO [main] [] extensions - extension com.zimbra.clam.ClamScanner found in /opt/zimbra/lib/ext/clamscanner/clamscanner.jar
2007-03-17 14:53:43,213 INFO [main] [] extensions - Initializing extensions
2007-03-17 14:53:43,219 INFO [main] [] ClamScanner - attachment scan is disabled
2007-03-17 14:53:43,309 INFO [main] [] extensions - Initialized extension clamscanner: com.zimbra.clam.ClamScanner@com.zimb...tensio n.ZimbraExtensionClassLoader@1ce663c
2007-03-17 14:53:43,327 INFO [IndexWritersSweeper] [] MailboxIndex - IndexWritersSweeper thread starting
2007-03-17 14:53:43,357 INFO [main] [] RedoLogManager - Starting pre-startup crash recovery
2007-03-17 14:53:43,388 INFO [main] [] RedoPlayer - No uncommitted transactions to redo
2007-03-17 14:53:43,398 INFO [main] [] RedoLogManager - Finished pre-startup crash recovery
2007-03-17 14:53:43,530 INFO [NOTIFY-7035-Server] [] misc - starting server loop
2007-03-17 14:53:43,534 INFO [LmtpServer] [] TcpServer/7025 - starting accept loop
2007-03-17 14:53:43,534 INFO [Pop3Server] [] TcpServer/110 - starting accept loop
2007-03-17 14:53:43,561 INFO [Pop3Server] [] TcpServer/995 - starting accept loop
2007-03-17 14:53:43,572 INFO [ImapServer] [] TcpServer/143 - starting accept loop
2007-03-17 14:53:43,586 INFO [ImapSSLServer] [] TcpServer/993 - starting accept loop
2007-03-17 14:53:43,587 INFO [main] [] soap - Servlet AdminServlet starting up
2007-03-17 14:53:43,683 INFO [main] [] soap - Adding service AdminService to AdminServlet
2007-03-17 14:53:43,704 INFO [main] [] soap - Adding service AccountService to AdminServlet
2007-03-17 14:53:43,704 INFO [main] [] soap - Adding service MailService to AdminServlet
2007-03-17 14:53:43,705 INFO [main] [] soap - Adding service IMService to AdminServlet
2007-03-17 14:53:43,710 INFO [main] [] ContentServlet - Servlet ContentServlet starting up
2007-03-17 14:53:43,712 INFO [main] [] PublicICalServlet - Servlet PublicICalServlet starting up
2007-03-17 14:53:43,714 INFO [main] [] account - Servlet PreAuthServlet starting up
2007-03-17 14:53:43,758 INFO [main] [] mailbox - Servlet UserServlet starting up
2007-03-17 14:53:43,766 INFO [main] [] FileUploadServlet - Servlet FileUploadServlet starting up
2007-03-17 14:53:43,769 INFO [main] [] StatsImageServlet - Servlet StatsImageServlet starting up
2007-03-17 15:26:49,014 INFO [LmtpServer-1] [] LmtpHandler - [192.168.22.152] connected
2007-03-17 15:26:49,689 INFO [LmtpServer-1] [] cache - Initializing folder and tag caches for mailbox 2
2007-03-17 15:26:49,820 INFO [IncomingDirectorySweeper] [] FileBlobStore - IncomingDirectorySweeper thread starting
2007-03-17 15:26:49,864 INFO [LmtpServer-1] [] JMSession - SMTP Server: zimbra.net18.com
2007-03-17 15:26:50,278 INFO [LmtpServer-1] [] zimlet - Loaded class com.zimbra.cs.zimlet.handler.NANPHandler
2007-03-17 15:26:50,304 INFO [LmtpServer-1] [] zimlet - Loaded class com.zimbra.cs.zimlet.handler.RegexHandler
2007-03-17 15:26:50,331 INFO [LmtpServer-1] [] cache - Initializing folder and tag caches for mailbox 3
2007-03-17 15:26:50,759 INFO [LmtpServer-1] [name=admin@net18.com;] mailbox - Added message id=440 digest=0Xh0JsW4vdyV5kzkuPBXgifTlC4= mailbox=2 rcpt=admin@net18.com
2007-03-17 15:26:50,887 INFO [LmtpServer-1] [name=ntbinh@net18.com;] mailbox - Added message id=580 digest=0Xh0JsW4vdyV5kzkuPBXgifTlC4= mailbox=3 rcpt=ntbinh@net18.com
2007-03-17 15:27:43,390 INFO [IndexWritersSweeper] [] MailboxIndex - open index writers sweep: before=2, closed=0, after=2 (0ms)
2007-03-17 15:28:30,912 INFO [LmtpServer-1] [] LmtpHandler - [192.168.22.152] quit from client
2007-03-17 15:28:30,912 INFO [LmtpServer-1] [] ProtocolHandler - Handler exiting normally
2007-03-17 15:28:43,392 INFO [IndexWritersSweeper] [] MailboxIndex - open index writers sweep: before=2, closed=0, after=2 (0ms)
2007-03-17 15:29:43,394 INFO [IndexWritersSweeper] [] MailboxIndex - open index writers sweep: before=2, closed=0, after=2 (0ms)
2007-03-17 15:30:43,394 INFO [IndexWritersSweeper] [] MailboxIndex - open index writers sweep: before=2, closed=0, after=2 (0ms)
2007-03-17 15:31:43,395 INFO [IndexWritersSweeper] [] MailboxIndex - open index writers sweep: before=2, closed=0, after=2 (0ms)
2007-03-17 15:32:43,397 INFO [IndexWritersSweeper] [] MailboxIndex - open index writers sweep: before=2, closed=0, after=2 (0ms)
2007-03-17 15:33:43,399 INFO [IndexWritersSweeper] [] MailboxIndex - open index writers sweep: before=2, closed=0, after=2 (0ms)
2007-03-17 15:34:43,401 INFO [IndexWritersSweeper] [] MailboxIndex - open index writers sweep: before=2, closed=0, after=2 (0ms)
2007-03-17 15:35:43,403 INFO [IndexWritersSweeper] [] MailboxIndex - open index writers sweep: before=2, closed=0, after=2 (0ms)
2007-03-17 15:36:43,405 INFO [IndexWritersSweeper] [] MailboxIndex - open index writers sweep: before=2, closed=0, after=2 (0ms)
2007-03-17 15:37:43,507 INFO [IndexWritersSweeper] [] MailboxIndex - open index writers sweep: before=2, closed=2, after=0 (101ms)

In zimbra.log, errors happen as i post for you.
Thanks.

jholder · #4 (**permalink**) 03-18-2007, 11:31 PM

Welp
There are certainly no errors in there. All of those lines are normal.
Are you sure zmcontrol status doesn't show everything working?

-john

zibra · #5 (**permalink**) 03-18-2007, 11:34 PM

Hi,
When i use zmcontrol status, it shows "Cannot determine services - exiting".

Please check logs again.

Mar 17 19:14:03 zimbra zimbramon[13492]: 13492:info: 2007-03-17 19:14:01, STATUS: zimbra.net18.com: antispam: Running
Mar 17 19:14:03 zimbra zimbramon[13492]: 13492:info: 2007-03-17 19:14:01, STATUS: zimbra.net18.com: antivirus: Running
Mar 17 19:14:03 zimbra zimbramon[13492]: 13492:info: 2007-03-17 19:14:01, STATUS: zimbra.net18.com: ldap: Running
Mar 17 19:14:03 zimbra zimbramon[13492]: 13492:info: 2007-03-17 19:14:01, STATUS: zimbra.net18.com: logger: Running
Mar 17 19:14:03 zimbra zimbramon[13492]: 13492:info: 2007-03-17 19:14:01, STATUS: zimbra.net18.com: mailbox: Running
Mar 17 19:14:03 zimbra zimbramon[13492]: 13492:info: 2007-03-17 19:14:01, STATUS: zimbra.net18.com: mta: Running
Mar 17 19:14:03 zimbra zimbramon[13492]: 13492:info: 2007-03-17 19:14:01, STATUS: zimbra.net18.com: snmp: Running
Mar 17 19:14:03 zimbra crond(pam_unix)[13491]: session closed for user zimbra
Mar 17 19:15:01 zimbra crond(pam_unix)[13958]: session opened for user mailman by (uid=0)
Mar 17 19:15:01 zimbra crond(pam_unix)[13958]: session closed for user mailman
Mar 17 19:56:34 zimbra sshd(pam_unix)[2760]: session opened for user root by root(uid=0)
Mar 17 19:56:34 zimbra su(pam_unix)[2784]: session opened for user zimbra by (uid=0)
Mar 17 19:56:42 zimbra zimbramon[2848]: 2848:info: Starting services
Mar 17 19:56:43 zimbra slapd[3134]: @(#) $OpenLDAP: slapd 2.3.21 (Feb 9 2007 14:47:10) $ build@build.liquidsys.com:/home/build/p4/main/
ThirdParty/openldap/openldap-2.3.21/servers/slapd
Mar 17 19:56:51 zimbra zimbramon[2848]: 2848:info: Rewriting configs antispam antivirus webxml mailbox antispam antivirus mta sasl
Mar 17 19:56:57 zimbra zimbramon[2848]: 2848:info: Starting logger
Mar 17 19:56:57 zimbra zimbramon[2848]: 2848:info: Starting mailbox
Mar 17 19:57:00 zimbra zmtomcatmgr[3912]: status requested
Mar 17 19:57:00 zimbra zmtomcatmgr[3912]: stale pid 5497 found in /opt/zimbra/log/zmtomcatmgr.pid: No such process
Mar 17 19:57:00 zimbra zmtomcatmgr[3912]: assuming no other instance is running
Mar 17 19:57:00 zimbra zmtomcatmgr[3912]: no manager process is running
Mar 17 19:57:03 zimbra zmtomcatmgr[3960]: start requested
Mar 17 19:57:03 zimbra zmtomcatmgr[3960]: checking if another instance of manager is already running
Mar 17 19:57:03 zimbra zmtomcatmgr[3960]: stale pid 5497 found in /opt/zimbra/log/zmtomcatmgr.pid: No such process
Mar 17 19:57:03 zimbra zmtomcatmgr[3960]: assuming no other instance is running
Mar 17 19:57:03 zimbra zmtomcatmgr[3961]: wrote manager pid 3961 to /opt/zimbra/log/zmtomcatmgr.pid
Mar 17 19:57:03 zimbra zmtomcatmgr[3961]: manager started tomcat/JVM with pid 3962
Mar 17 19:57:03 zimbra zimbramon[2848]: 2848:info: Starting antispam
Mar 17 19:57:05 zimbra zimbramon[2848]: 2848:info: Starting antivirus
Mar 17 19:57:05 zimbra zimbramon[2848]: 2848:info: Starting snmp
Mar 17 19:57:06 zimbra zimbramon[2848]: 2848:info: Starting mta
Mar 17 19:57:07 zimbra clamd[4218]: Daemon started.
Mar 17 19:57:07 zimbra clamd[4218]: clamd daemon 0.88.7 (OS: linux-gnu, ARCH: i386, CPU: i686)
Mar 17 19:57:07 zimbra clamd[4218]: Log file size limited to 20971520 bytes.
Mar 17 19:57:07 zimbra clamd[4218]: Reading databases from /opt/zimbra/clamav/db
Mar 17 12:57:07 zimbra postfix/postqueue[4309]: fatal: Queue report unavailable - mail system is down
Mar 17 19:57:18 zimbra postfix/postfix-script: warning: not owned by root: /opt/zimbra/postfix-2.2.9/conf/main.cf
Mar 17 19:57:18 zimbra clamd[4218]: Protecting against 90909 viruses.
Mar 17 19:57:18 zimbra clamd[4356]: Bound to port 3310
Mar 17 19:57:18 zimbra clamd[4356]: Setting connection queue length to 15
Mar 17 19:57:18 zimbra clamd[4356]: Archive: Archived file size limit set to 104857600 bytes.
Mar 17 19:57:18 zimbra clamd[4356]: Archive: Recursion level limit set to 8.
Mar 17 19:57:18 zimbra clamd[4356]: Archive: Files limit set to 1000.
Mar 17 19:57:18 zimbra clamd[4356]: Archive: Compression ratio limit set to 250.
Mar 17 19:57:18 zimbra clamd[4356]: Archive support enabled.
Mar 17 19:57:18 zimbra clamd[4356]: Archive: RAR support disabled.
Mar 17 19:57:18 zimbra clamd[4356]: Portable Executable support enabled.
Mar 17 19:57:18 zimbra clamd[4356]: Mail files support enabled.
Mar 17 19:57:18 zimbra clamd[4356]: Mail: Recursion level limit set to 64.
Mar 17 19:57:18 zimbra clamd[4356]: OLE2 support enabled.
Mar 17 19:57:18 zimbra clamd[4356]: HTML support enabled.
Mar 17 19:57:18 zimbra clamd[4356]: Self checking every 1800 seconds.
Mar 17 19:57:18 zimbra postfix/postfix-script: starting the Postfix mail system
Mar 17 19:57:18 zimbra postfix/master[4366]: daemon started -- version 2.2.9, configuration /opt/zimbra/postfix-2.2.9/conf
Mar 17 19:57:22 zimbra saslauthd[4412]: detach_tty : master pid is: 4412
Mar 17 19:57:22 zimbra saslauthd[4412]: ipc_init : listening on socket: /opt/zimbra/cyrus-sasl-2.1.21.ZIMBRA/state/mux
Mar 17 19:57:22 zimbra su(pam_unix)[2784]: session closed for user zimbra
Mar 17 19:57:31 zimbra amavis[4132]: starting. /opt/zimbra/amavisd/sbin/amavisd at zimbra.net18.com amavisd-new-2.4.3 (20060930), Unicode awa
re, LANG=en_US.UTF-8
Mar 17 19:57:31 zimbra amavis[4132]: user=506, EUID: 506 (506); group=, EGID: 505 505 503 5 4 (505 505 503 5 4); log_level=1
Mar 17 19:57:31 zimbra amavis[4132]: Perl version 5.008005
Mar 17 19:57:36 zimbra amavis[4132]: SpamControl: init_pre_chroot done
Mar 17 19:57:36 zimbra amavis[5019]: Net::Server: Process Backgrounded
Mar 17 19:57:36 zimbra amavis[5019]: Net::Server: 2007/03/17-19:57:36 Amavis (type Net::Server::PreForkSimple) starting! pid(5019)
Mar 17 19:57:36 zimbra amavis[5019]: Net::Server: Binding to UNIX socket file /opt/zimbra/amavisd/amavisd.sock using SOCK_STREAM
Mar 17 19:57:36 zimbra amavis[5019]: Net::Server: Binding to TCP port 10024 on host 127.0.0.1
Mar 17 19:57:36 zimbra amavis[5019]: Net::Server: Group Not Defined. Defaulting to EGID '505 505 503 5 4'
Mar 17 19:57:36 zimbra amavis[5019]: Net::Server: User Not Defined. Defaulting to EUID '506'
Mar 17 19:57:36 zimbra amavis[5019]: Module Amavis::Conf 2.072
Mar 17 19:57:36 zimbra amavis[5019]: Module Archive::Tar 1.30
Mar 17 19:57:36 zimbra amavis[5019]: Module Archive::Zip 1.18
Mar 17 19:57:36 zimbra amavis[5019]: Module BerkeleyDB 0.31
Mar 17 19:57:36 zimbra amavis[5019]: Module Compress::Zlib 2.003
Mar 17 19:57:36 zimbra amavis[5019]: Module Convert::TNEF 0.17
Mar 17 19:57:36 zimbra amavis[5019]: Module Convert::UUlib 1.08
Mar 17 19:57:36 zimbra amavis[5019]: Module DBD::Pg 1.31
Mar 17 19:57:36 zimbra amavis[5019]: Module DBD::mysql 4.001
Mar 17 19:57:36 zimbra amavis[5019]: Module DBI 1.53
Mar 17 19:57:36 zimbra amavis[5019]: Module DB_File 1.815
Mar 17 19:57:36 zimbra amavis[5019]: Module Digest::MD5 2.36
Mar 17 19:57:36 zimbra amavis[5019]: Module MIME::Entity 5.420
Mar 17 19:57:36 zimbra amavis[5019]: Module MIME::Parser 5.420
Mar 17 19:57:36 zimbra amavis[5019]: Module MIME::Tools 5.420
Mar 17 19:57:36 zimbra amavis[5019]: Module Mail::Header 1.74
Mar 17 19:57:36 zimbra amavis[5019]: Module Mail::Internet 1.74
Mar 17 19:57:36 zimbra amavis[5019]: Module Mail::SpamAssassin 3.001007
Mar 17 19:57:36 zimbra amavis[5019]: Module Net::Cmd 2.27
Mar 17 19:57:36 zimbra amavis[5019]: Module Net:

NS 0.59
Mar 17 19:57:36 zimbra amavis[5019]: Module Net::LDAP 0.33
Mar 17 19:57:36 zimbra amavis[5019]: Module Net::SMTP 2.30
Mar 17 19:57:36 zimbra amavis[5019]: Module Net::Server 0.95
Mar 17 19:57:36 zimbra amavis[5019]: Module Time::HiRes 1.9704
Mar 17 19:57:36 zimbra amavis[5019]: Module Unix::Syslog 0.99
Mar 17 19:57:36 zimbra amavis[5019]: Amavis:

B code loaded
Mar 17 19:57:36 zimbra amavis[5019]: Amavis::Cache code loaded
Mar 17 19:57:36 zimbra amavis[5019]: SQL base code NOT loaded
Mar 17 19:57:36 zimbra amavis[5019]: SQL::Log code NOT loaded
Mar 17 19:57:36 zimbra amavis[5019]: SQL::Quarantine NOT loaded
Mar 17 19:57:36 zimbra amavis[5019]: Lookup::SQL code NOT loaded
Mar 17 19:57:36 zimbra amavis[5019]: Lookup::LDAP code loaded
Mar 17 19:57:36 zimbra amavis[5019]: AM.PDP-in proto code loaded
Mar 17 19:57:36 zimbra amavis[5019]: SMTP-in proto code loaded
Mar 17 19:57:36 zimbra amavis[5019]: Courier proto code NOT loaded
Mar 17 19:57:36 zimbra amavis[5019]: SMTP-out proto code loaded
Mar 17 19:57:36 zimbra amavis[5019]: Pipe-out proto code NOT loaded
Mar 17 19:57:36 zimbra amavis[5019]: BSMTP-out proto code NOT loaded
Mar 17 19:57:36 zimbra amavis[5019]: Local-out proto code loaded
Mar 17 19:57:36 zimbra amavis[5019]: OS_Fingerprint code NOT loaded
Mar 17 19:57:36 zimbra amavis[5019]: ANTI-VIRUS code loaded
Mar 17 19:57:36 zimbra amavis[5019]: ANTI-SPAM code loaded
Mar 17 19:57:36 zimbra amavis[5019]: ANTI-SPAM-SA code loaded
Mar 17 19:57:36 zimbra amavis[5019]: Unpackers code loaded
Mar 17 19:57:36 zimbra amavis[5019]: Found $file at /usr/bin/file
Mar 17 19:57:36 zimbra amavis[5019]: No $dspam, not using it
Mar 17 19:57:36 zimbra amavis[5019]: Internal decoder for .mail
Mar 17 19:57:36 zimbra amavis[5019]: Internal decoder for .asc
Mar 17 19:57:36 zimbra amavis[5019]: Internal decoder for .uue
Mar 17 19:57:36 zimbra amavis[5019]: Internal decoder for .hqx
Mar 17 19:57:36 zimbra amavis[5019]: Internal decoder for .ync
Mar 17 19:57:36 zimbra amavis[5019]: No decoder for .F tried: unfreeze, freeze -d, melt, fcat
Mar 17 19:57:36 zimbra amavis[5019]: Found decoder for .Z at /usr/bin/gzip -d
Mar 17 19:57:36 zimbra amavis[5019]: Internal decoder for .gz
Mar 17 19:57:36 zimbra amavis[5019]: Found decoder for .bz2 at /usr/bin/bzip2 -d
Mar 17 19:57:36 zimbra amavis[5019]: No decoder for .lzo tried: lzop -d
Mar 17 19:57:36 zimbra amavis[5019]: Found decoder for .rpm at /usr/bin/rpm2cpio
Mar 17 19:57:36 zimbra amavis[5019]: Found decoder for .cpio at /usr/bin/pax
Mar 17 19:57:36 zimbra amavis[5019]: Found decoder for .tar at /usr/bin/pax
Mar 17 19:57:36 zimbra amavis[5019]: Found decoder for .deb at /usr/bin/ar
Mar 17 19:57:36 zimbra amavis[5019]: Internal decoder for .zip
Mar 17 19:57:36 zimbra amavis[5019]: Found decoder for .rar at /usr/bin/unrar
Mar 17 19:57:36 zimbra amavis[5019]: No decoder for .arj tried: arj, unarj
Mar 17 19:57:36 zimbra amavis[5019]: No decoder for .arc tried: nomarch, arc
Mar 17 19:57:36 zimbra amavis[5019]: No decoder for .zoo tried: zoo
Mar 17 19:57:36 zimbra amavis[5019]: Found decoder for .lha at /usr/bin/lha
Mar 17 19:57:36 zimbra amavis[5019]: No decoder for .cab tried: cabextract
Mar 17 19:57:36 zimbra amavis[5019]: No decoder for .tnef tried: tnef
Mar 17 19:57:36 zimbra amavis[5019]: Internal decoder for .tnef
Mar 17 19:57:36 zimbra amavis[5019]: Found decoder for .exe at /usr/bin/unrar; /usr/bin/lha
Mar 17 19:57:36 zimbra amavis[5019]: Using internal av scanner code for (primary) ClamAV-clamd
Mar 17 19:57:36 zimbra amavis[5019]: Creating db in /opt/zimbra/amavisd/db/; BerkeleyDB 0.31, libdb 4.2
Mar 17 19:57:36 zimbra amavis[5019]: SpamControl: initializing Mail::SpamAssassin
Mar 17 19:57:46 zimbra amavis[5019]: SpamControl: init_pre_fork done
Mar 17 19:58:02 zimbra crond(pam_unix)[5127]: session opened for user zimbra by (uid=0)
Mar 17 19:58:07 zimbra zimbramon[5129]: 5129:info: 2007-03-17 19:58:02, STATUS: : Cannot: determine
Mar 17 19:58:07 zimbra crond(pam_unix)[5127]: session closed for user zimbra
Mar 17 20:00:01 zimbra crond(pam_unix)[5466]: session opened for user zimbra by (uid=0)
Mar 17 20:00:01 zimbra crond(pam_unix)[5469]: session opened for user zimbra by (uid=0)
Mar 17 20:00:01 zimbra crond(pam_unix)[5471]: session opened for user zimbra by (uid=0)
Mar 17 20:00:01 zimbra crond(pam_unix)[5473]: session opened for user mailman by (uid=0)
Mar 17 20:00:01 zimbra crond(pam_unix)[5465]: session opened for user zimbra by (uid=0)
Mar 17 20:00:02 zimbra zimbramon[5475]: 5475:info: 2007-03-17 20:00:01, QUEUE: 0 0
Mar 17 20:00:02 zimbra crond(pam_unix)[5465]: session closed for user zimbra
Mar 17 20:00:02 zimbra zimbramon[5470]: 5470:info: 2007-03-17 20:00:01, DISK: zimbra.net18.com: dev: /dev/hda3, mp: /, tot: 36452, avail: 5897

Mar 17 20:00:02 zimbra zimbramon[5470]: 5470:info: 2007-03-17 20:00:01, DISK: zimbra.net18.com: dev: /dev/hda1, mp: /boot, tot: 99, avail: 86
Mar 17 20:00:02 zimbra crond(pam_unix)[5469]: session closed for user zimbra
Mar 17 20:00:03 zimbra zimbramon[5472]: 5472:info: 2007-03-17 20:00:01, STATUS: : Cannot: determine
Mar 17 20:00:03 zimbra crond(pam_unix)[5471]: session closed for user zimbra
Mar 17 20:00:05 zimbra crond(pam_unix)[5473]: session closed for user mailman
Mar 17 20:00:06 zimbra crond(pam_unix)[5466]: session closed for user zimbra
Mar 17 20:01:01 zimbra crond(pam_unix)[5774]: session opened for user root by (uid=0)
Mar 17 20:01:01 zimbra crond(pam_unix)[5774]: session closed for user root
Mar 17 20:02:01 zimbra crond(pam_unix)[5951]: session opened for user zimbra by (uid=0)
Mar 17 20:02:02 zimbra zimbramon[5952]: 5952:info: 2007-03-17 20:02:01, STATUS: : Cannot: determine
Mar 17 20:02:02 zimbra crond(pam_unix)[5951]: session closed for user zimbra
Mar 17 20:04:01 zimbra crond(pam_unix)[6328]: session opened for user zimbra by (uid=0)
Mar 17 20:04:02 zimbra zimbramon[6329]: 6329:info: 2007-03-17 20:04:01, STATUS: : Cannot: determine
Mar 17 20:04:02 zimbra crond(pam_unix)[6328]: session closed for user zimbra
Mar 17 20:05:01 zimbra crond(pam_unix)[6534]: session opened for user mailman by (uid=0)
Mar 17 20:05:01 zimbra crond(pam_unix)[6534]: session closed for user mailman
Mar 17 20:06:01 zimbra crond(pam_unix)[6709]: session opened for user zimbra by (uid=0)
Mar 17 20:06:02 zimbra zimbramon[6710]: 6710:info: 2007-03-17 20:06:01, STATUS: : Cannot: determine
Mar 17 20:06:02 zimbra crond(pam_unix)[6709]: session closed for user zimbra
Mar 17 20:08:01 zimbra crond(pam_unix)[7086]: session opened for user zimbra by (uid=0)
Mar 17 20:08:02 zimbra zimbramon[7087]: 7087:info: 2007-03-17 20:08:01, STATUS: : Cannot: determine
Mar 17 20:08:02 zimbra crond(pam_unix)[7086]: session closed for user zimbra
Mar 17 20:10:01 zimbra crond(pam_unix)[7464]: session opened for user zimbra by (uid=0)
Mar 17 20:10:01 zimbra zimbramon[7465]: 7465:info: 2007-03-17 20:10:01, QUEUE: 0 0
Mar 17 20:10:01 zimbra crond(pam_unix)[7466]: session opened for user zimbra by (uid=0)
Mar 17 20:10:01 zimbra crond(pam_unix)[7477]: session opened for user zimbra by (uid=0)
Mar 17 20:10:01 zimbra crond(pam_unix)[7479]: session opened for user zimbra by (uid=0)
Mar 17 20:10:01 zimbra crond(pam_unix)[7481]: session opened for user mailman by (uid=0)
Mar 17 20:10:01 zimbra crond(pam_unix)[7464]: session closed for user zimbra
Mar 17 20:10:01 zimbra crond(pam_unix)[7467]: session opened for user zimbra by (uid=0)
Mar 17 20:10:01 zimbra crond(pam_unix)[7481]: session closed for user mailman
Mar 17 20:10:02 zimbra zimbramon[7478]: 7478:info: 2007-03-17 20:10:01, DISK: zimbra.net18.com: dev: /dev/hda3, mp: /, tot: 36452, avail: 5896

Mar 17 20:10:02 zimbra zimbramon[7478]: 7478:info: 2007-03-17 20:10:01, DISK: zimbra.net18.com: dev: /dev/hda1, mp: /boot, tot: 99, avail: 86
Mar 17 20:10:02 zimbra crond(pam_unix)[7477]: session closed for user zimbra
Mar 17 20:10:04 zimbra zimbramon[7480]: 7480:info: 2007-03-17 20:10:01, STATUS: : Cannot: determine
Mar 17 20:10:04 zimbra crond(pam_unix)[7479]: session closed for user zimbra
Mar 17 20:10:06 zimbra crond(pam_unix)[7467]: session closed for user zimbra
Mar 17 20:10:08 zimbra crond(pam_unix)[7466]: session closed for user zimbra
Mar 17 20:12:01 zimbra crond(pam_unix)[9096]: session opened for user zimbra by (uid=0)
Mar 17 20:12:02 zimbra zimbramon[9097]: 9097:info: 2007-03-17 20:12:01, STATUS: : Cannot: determine
Mar 17 20:12:02 zimbra crond(pam_unix)[9096]: session closed for user zimbra
Mar 17 20:14:01 zimbra crond(pam_unix)[9474]: session opened for user zimbra by (uid=0)
Mar 17 20:14:02 zimbra zimbramon[9475]: 9475:info: 2007-03-17 20:14:01, STATUS: : Cannot: determine
Mar 17 20:14:02 zimbra crond(pam_unix)[9474]: session closed for user zimbra
Mar 17 20:15:01 zimbra crond(pam_unix)[9679]: session opened for user zimbra by (uid=0)
Mar 17 20:15:01 zimbra crond(pam_unix)[9682]: session opened for user mailman by (uid=0)
Mar 17 20:15:01 zimbra crond(pam_unix)[9679]: session closed for user zimbra
Mar 17 20:15:01 zimbra crond(pam_unix)[9682]: session closed for user mailman
Mar 17 20:16:01 zimbra crond(pam_unix)[9857]: session opened for user zimbra by (uid=0)
Mar 17 20:16:02 zimbra zimbramon[9858]: 9858:info: 2007-03-17 20:16:01, STATUS: : Cannot: determine
Mar 17 20:16:02 zimbra crond(pam_unix)[9857]: session closed for user zimbra
Mar 17 20:18:01 zimbra crond(pam_unix)[10234]: session opened for user zimbra by (uid=0)
Mar 17 20:18:02 zimbra zimbramon[10235]: 10235:info: 2007-03-17 20:18:01, STATUS: : Cannot: determine
Mar 17 20:18:02 zimbra crond(pam_unix)[10234]: session closed for user zimbra
Mar 17 20:20:01 zimbra crond(pam_unix)[10615]: session opened for user zimbra by (uid=0)
Mar 17 20:20:01 zimbra crond(pam_unix)[10617]: session opened for user zimbra by (uid=0)
Mar 17 20:20:01 zimbra crond(pam_unix)[10621]: session opened for user zimbra by (uid=0)
Mar 17 20:20:01 zimbra crond(pam_unix)[10623]: session opened for user mailman by (uid=0)
Mar 17 20:20:01 zimbra zimbramon[10616]: 10616:info: 2007-03-17 20:20:01, QUEUE: 0 0
Mar 17 20:20:01 zimbra crond(pam_unix)[10615]: session closed for user zimbra
Mar 17 20:20:01 zimbra crond(pam_unix)[10618]: session opened for user zimbra by (uid=0)
Mar 17 20:20:01 zimbra crond(pam_unix)[10623]: session closed for user mailman
Mar 17 20:20:02 zimbra zimbramon[10632]: 10632:info: 2007-03-17 20:20:01, DISK: zimbra.net18.com: dev: /dev/hda3, mp: /, tot: 36452, avail: 58
96
Mar 17 20:20:02 zimbra zimbramon[10632]: 10632:info: 2007-03-17 20:20:01, DISK: zimbra.net18.com: dev: /dev/hda1, mp: /boot, tot: 99, avail: 8
6
Mar 17 20:20:02 zimbra crond(pam_unix)[10618]: session closed for user zimbra
Mar 17 20:20:04 zimbra zimbramon[10622]: 10622:info: 2007-03-17 20:20:01, STATUS: : Cannot: determine
Mar 17 20:20:04 zimbra crond(pam_unix)[10621]: session closed for user zimbra
Mar 17 20:20:04 zimbra crond(pam_unix)[10617]: session closed for user zimbra
Mar 17 20:22:01 zimbra crond(pam_unix)[11096]: session opened for user zimbra by (uid=0)
Mar 17 20:22:02 zimbra zimbramon[11097]: 11097:info: 2007-03-17 20:22:02, STATUS: : Cannot: determine
Mar 17 20:22:02 zimbra crond(pam_unix)[11096]: session closed for user zimbra
Mar 17 20:24:01 zimbra crond(pam_unix)[11475]: session opened for user zimbra by (uid=0)
Mar 17 20:24:02 zimbra zimbramon[11476]: 11476:info: 2007-03-17 20:24:02, STATUS: : Cannot: determine
Mar 17 20:24:02 zimbra crond(pam_unix)[11475]: session closed for user zimbra
Mar 17 20:25:01 zimbra crond(pam_unix)[11680]: session opened for user mailman by (uid=0)
Mar 17 20:25:02 zimbra crond(pam_unix)[11680]: session closed for user mailman

Thank you very much.

PhilF · #6 (**permalink**) 03-22-2007, 11:34 AM

This sounds at least slightly similar to another thread:
Big problem after "out of disk space"

Is it possible that you are running low on disk space? It doesn't necessarily mean that you are _out_ of space; some systems reserve a small amount of space (5% or 10%) for use only by the root user.