-
Error after installation
Hi,
My name is Robson of the Brazil.
I have one problem in my server with zimbra, after installation the log show message in file /opt/zimbra/log/zmmailboxd.out:
“fatal error: exception while binding to ports”
The services of mailbox, mta and postfix not running.
I would like that help me.
Thanks.
:confused::confused:
-
It would help if you'd posted the log as an attachment but from the information you've mentioned you probably have a mail server already installed - stop the mailserver and disable it from running at boot.
-
Hi,
Thanks my server is running very good.
-
Now my server not send mail and not receive mail.
Not show error message, I use web browser.
Thanks.
-
You're not actually giving us much information to work with and this sort of problem has been covered many times in the forums, please search.
Do you have correct DNS A & MX records plus a correct /etc/hosts file? If you're behind a NAT router do you have a Split DNS set-up? Are all the Zimbra services running? What errors do you see in the log files?
-
Mailbox.log
2008-04-24 06:22:05,902 INFO [btpool0-3] [name=robson@server.rbnmateus.com.br;mid=5;ip=192.1 68.0.175;ua=ZimbraWebClient - IE7 (Win)/5.0.4_GA_2101.DEBIAN4.0;] JMSession - SMTP Server: server.rbnmateus.com.br
2008-04-24 06:22:06,463 INFO [btpool0-3] [name=robson@server.rbnmateus.com.br;mid=5;ip=192.1 68.0.175;ua=ZimbraWebClient - IE7 (Win)/5.0.4_GA_2101.DEBIAN4.0;] smtp - Sending message: Message-ID=<25932797.01209028926462.JavaMail.root@server>, replyType=r
2008-04-24 06:22:07,105 INFO [btpool0-3] [name=robson@server.rbnmateus.com.br;mid=5;ip=192.1 68.0.175;ua=ZimbraWebClient - IE7 (Win)/5.0.4_GA_2101.DEBIAN4.0;] zimlet - Loaded class com.zimbra.cs.zimlet.handler.NANPHandler
2008-04-24 06:22:07,125 INFO [btpool0-3] [name=robson@server.rbnmateus.com.br;mid=5;ip=192.1 68.0.175;ua=ZimbraWebClient - IE7 (Win)/5.0.4_GA_2101.DEBIAN4.0;] zimlet - Loaded class com.zimbra.cs.zimlet.handler.RegexHandler
2008-04-24 06:22:07,933 INFO [btpool0-3] [name=robson@server.rbnmateus.com.br;mid=5;ip=192.1 68.0.175;ua=ZimbraWebClient - IE7 (Win)/5.0.4_GA_2101.DEBIAN4.0;] mailbox - Added message: id=257, digest=+cqPbstMjPWKPNuSYG,nyU0fv80=, folderId=5, folderName=Sent
2008-04-24 06:22:11,968 INFO [IndexWritersSweeperThread] [] index - open index writers sweep: before=1, closed=0, after=1 (0ms)
2008-04-24 06:22:21,263 INFO [MailboxPurge] [name=ham.c6xxmsi1zy@server.rbnmateus.com.br;mid=4;] purge - Purging messages.
2008-04-24 06:22:41,998 INFO [IndexWritersSweeperThread] [] index - open index writers sweep: before=1, closed=0, after=1 (0ms)
2008-04-24 06:23:03,447 INFO [btpool0-0] [name=robson@server.rbnmateus.com.br;mid=5;ip=192.1 68.0.175;ua=ZimbraWebClient - IE7 (Win)/5.0.4_GA_2101.DEBIAN4.0;] SendMsg - <SendMsg> LC(mbox=e2f56bff-e2b9-4319-9562-c9791fddcd58, sessions=15)
2008-04-24 06:23:03,459 INFO [btpool0-0] [name=robson@server.rbnmateus.com.br;mid=5;ip=192.1 68.0.175;ua=ZimbraWebClient - IE7 (Win)/5.0.4_GA_2101.DEBIAN4.0;] smtp - Sending message: Message-ID=<29921964.31209028983459.JavaMail.root@server>, replyType=r
2008-04-24 06:23:03,723 INFO [btpool0-0] [name=robson@server.rbnmateus.com.br;mid=5;ip=192.1 68.0.175;ua=ZimbraWebClient - IE7 (Win)/5.0.4_GA_2101.DEBIAN4.0;] mailbox - Added message: id=260, digest=VcXAFoVu8CScki51BXnj2S0K,T4=, folderId=5, folderName=Sent
2008-04-24 06:23:03,969 WARN [btpool0-0] [name=robson@server.rbnmateus.com.br;mid=5;ip=192.1 68.0.175;ua=ZimbraWebClient - IE7 (Win)/5.0.4_GA_2101.DEBIAN4.0;] smtp - Exception occurred during SendMsg:
com.zimbra.cs.mailbox.MailSender$SafeSendFailedExc eption: 504 5.5.2 <robson>: Recipient address rejected: need fully-qualified address
; chained exception is:
com.sun.mail.smtp.SMTPAddressFailedException: 504 5.5.2 <robson>: Recipient address rejected: need fully-qualified address
at com.sun.mail.smtp.SMTPTransport.rcptTo(SMTPTranspo rt.java:1281)
at com.sun.mail.smtp.SMTPTransport.sendMessage(SMTPTr ansport.java:622)
at javax.mail.Transport.send0(Transport.java:169)
-
Do you actually have a DNS server or domain name with correct DNS records set-up? Run the following commands on your zimbra server:
Code:
host `hostname` <-- type as-is and use backticks not single quotes
dig yourdomain.com mx
dig yourdomain.com any
and post the output here.
-
Output of the command
; <<>> DiG 9.3.4 <<>> rbnmateus.com.br any
;; global options: printcmd
;; Got answer:
;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 50616
;; flags: qr aa rd ra; QUERY: 1, ANSWER: 3, AUTHORITY: 0, ADDITIONAL: 1
;; QUESTION SECTION:
;rbnmateus.com.br. IN ANY
;; ANSWER SECTION:
rbnmateus.com.br. 86400 IN SOA ns.rbnmateus.com.br. root.ns.rbnmateus.com.br. 1 28800 7200 604800 86400
rbnmateus.com.br. 86400 IN NS ns.rbnmateus.com.br.
rbnmateus.com.br. 86400 IN MX 10 server.rbnmateus.com.br.
;; ADDITIONAL SECTION:
ns.rbnmateus.com.br. 86400 IN A 192.168.0.167
;; Query time: 1 msec
;; SERVER: 127.0.0.1#53(127.0.0.1)
;; WHEN: Tue Apr 29 08:23:13 2008
;; MSG SIZE rcvd: 131
================================================== =======
MX
================================================== =======
; <<>> DiG 9.3.4 <<>> rbnmateus.com.br any
;; global options: printcmd
;; Got answer:
;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 50616
;; flags: qr aa rd ra; QUERY: 1, ANSWER: 3, AUTHORITY: 0, ADDITIONAL: 1
;; QUESTION SECTION:
;rbnmateus.com.br. IN ANY
;; ANSWER SECTION:
rbnmateus.com.br. 86400 IN SOA ns.rbnmateus.com.br. root.ns.rbnmateus.com.br. 1 28800 7200 604800 86400
rbnmateus.com.br. 86400 IN NS ns.rbnmateus.com.br.
rbnmateus.com.br. 86400 IN MX 10 server.rbnmateus.com.br.
;; ADDITIONAL SECTION:
ns.rbnmateus.com.br. 86400 IN A 192.168.0.167
;; Query time: 1 msec
;; SERVER: 127.0.0.1#53(127.0.0.1)
;; WHEN: Tue Apr 29 08:23:13 2008
;; MSG SIZE rcvd: 131
-
log zmmailboxd.out
"btpool0-6 - Acceptor1 SelectChannelConnector@0.0.0.0:7072" prio=10 tid=0x08349800 nid=0xcf3 runnable [0xa7059000..0xa7059f40]
java.lang.Thread.State: RUNNABLE
at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrappe r.java:215)
at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelecto rImpl.java:65)
at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorIm pl.java:69)
- locked <0xa9fcc410> (a sun.nio.ch.Util$1)
- locked <0xa9fcc420> (a java.util.Collections$UnmodifiableSet)
- locked <0xa9fcc3d0> (a sun.nio.ch.EPollSelectorImpl)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:8 0)
at org.mortbay.io.nio.SelectorManager$SelectSet.doSel ect(SelectorManager.java:416)
at org.mortbay.io.nio.SelectorManager.doSelect(Select orManager.java:168)
at org.mortbay.jetty.nio.SelectChannelConnector.accep t(SelectChannelConnector.java:124)
at org.mortbay.jetty.AbstractConnector$Acceptor.run(A bstractConnector.java:514)
at org.mortbay.thread.BoundedThreadPool$PoolThread.ru n(BoundedThreadPool.java:442)
"btpool0-5 - Acceptor0 SelectChannelConnector@0.0.0.0:7072" prio=10 tid=0x08348800 nid=0xcf2 runnable [0xa709a000..0xa709aec0]
java.lang.Thread.State: RUNNABLE
at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrappe r.java:215)
at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelecto rImpl.java:65)
at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorIm pl.java:69)
- locked <0xa9fcc518> (a sun.nio.ch.Util$1)
- locked <0xa9fcc528> (a java.util.Collections$UnmodifiableSet)
- locked <0xa9fcc4d8> (a sun.nio.ch.EPollSelectorImpl)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:8 0)
at org.mortbay.io.nio.SelectorManager$SelectSet.doSel ect(SelectorManager.java:416)
at org.mortbay.io.nio.SelectorManager.doSelect(Select orManager.java:168)
at org.mortbay.jetty.nio.SelectChannelConnector.accep t(SelectChannelConnector.java:124)
at org.mortbay.jetty.AbstractConnector$Acceptor.run(A bstractConnector.java:514)
at org.mortbay.thread.BoundedThreadPool$PoolThread.ru n(BoundedThreadPool.java:442)
"btpool0-4 - Acceptor1 SslSelectChannelConnector@0.0.0.0:7071" prio=10 tid=0x08247800 nid=0xcf1 runnable [0xa70db000..0xa70dbe40]
java.lang.Thread.State: RUNNABLE
at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrappe r.java:215)
at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelecto rImpl.java:65)
at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorIm pl.java:69)
- locked <0xa9fcc620> (a sun.nio.ch.Util$1)
- locked <0xa9fcc630> (a java.util.Collections$UnmodifiableSet)
- locked <0xa9fcc5e0> (a sun.nio.ch.EPollSelectorImpl)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:8 0)
at org.mortbay.io.nio.SelectorManager$SelectSet.doSel ect(SelectorManager.java:416)
at org.mortbay.io.nio.SelectorManager.doSelect(Select orManager.java:168)
at org.mortbay.jetty.nio.SelectChannelConnector.accep t(SelectChannelConnector.java:124)
at org.mortbay.jetty.AbstractConnector$Acceptor.run(A bstractConnector.java:514)
at org.mortbay.thread.BoundedThreadPool$PoolThread.ru n(BoundedThreadPool.java:442)
"btpool0-3 - Acceptor0 SslSelectChannelConnector@0.0.0.0:7071" prio=10 tid=0x08246400 nid=0xcf0 runnable [0xa711c000..0xa711d1c0]
java.lang.Thread.State: RUNNABLE
at sun.nio.ch.EPollArrayWrapper.epollWait(Native Method)
at sun.nio.ch.EPollArrayWrapper.poll(EPollArrayWrappe r.java:215)
at sun.nio.ch.EPollSelectorImpl.doSelect(EPollSelecto rImpl.java:65)
at sun.nio.ch.SelectorImpl.lockAndDoSelect(SelectorIm pl.java:69)
- locked <0xa9fcc728> (a sun.nio.ch.Util$1)
- locked <0xa9fcc738> (a java.util.Collections$UnmodifiableSet)
- locked <0xa9fcc6e8> (a sun.nio.ch.EPollSelectorImpl)
at sun.nio.ch.SelectorImpl.select(SelectorImpl.java:8 0)
at org.mortbay.io.nio.SelectorManager$SelectSet.doSel ect(SelectorManager.java:416)
at org.mortbay.io.nio.SelectorManager.doSelect(Select orManager.java:168)
at org.mortbay.jetty.nio.SelectChannelConnector.accep t(SelectChannelConnector.java:124)
at org.mortbay.jetty.AbstractConnector$Acceptor.run(A bstractConnector.java:514)
at org.mortbay.thread.BoundedThreadPool$PoolThread.ru n(BoundedThreadPool.java:442)
"btpool0-2" prio=10 tid=0x08245400 nid=0xcef in Object.wait() [0xa715d000..0xa715e140]
java.lang.Thread.State: TIMED_WAITING (on object monitor)
at java.lang.Object.wait(Native Method)
- waiting on <0xa99d05f0> (a org.mortbay.thread.BoundedThreadPool$PoolThread)
at org.mortbay.thread.BoundedThreadPool$PoolThread.ru n(BoundedThreadPool.java:482)
- locked <0xa99d05f0> (a org.mortbay.thread.BoundedThreadPool$PoolThread)
"btpool0-1" prio=10 tid=0x081efc00 nid=0xcee in Object.wait() [0xa719e000..0xa719f0c0]
java.lang.Thread.State: TIMED_WAITING (on object monitor)
at java.lang.Object.wait(Native Method)
- waiting on <0xa99d0660> (a org.mortbay.thread.BoundedThreadPool$PoolThread)
at org.mortbay.thread.BoundedThreadPool$PoolThread.ru n(BoundedThreadPool.java:482)
- locked <0xa99d0660> (a org.mortbay.thread.BoundedThreadPool$PoolThread)
"btpool0-0" prio=10 tid=0x081ef000 nid=0xced in Object.wait() [0xa71df000..0xa71e0040]
java.lang.Thread.State: TIMED_WAITING (on object monitor)
at java.lang.Object.wait(Native Method)
- waiting on <0xa99d06d0> (a org.mortbay.thread.BoundedThreadPool$PoolThread)
at org.mortbay.thread.BoundedThreadPool$PoolThread.ru n(BoundedThreadPool.java:482)
- locked <0xa99d06d0> (a org.mortbay.thread.BoundedThreadPool$PoolThread)
"Timer-0" prio=10 tid=0x08242000 nid=0xcec in Object.wait() [0xa7220000..0xa7220fc0]
java.lang.Thread.State: TIMED_WAITING (on object monitor)
at java.lang.Object.wait(Native Method)
- waiting on <0xa99d07b0> (a java.util.TaskQueue)
at java.util.TimerThread.mainLoop(Timer.java:509)
- locked <0xa99d07b0> (a java.util.TaskQueue)
at java.util.TimerThread.run(Timer.java:462)
"Low Memory Detector" daemon prio=10 tid=0x080c7c00 nid=0xc64 runnable [0x00000000..0x00000000]
java.lang.Thread.State: RUNNABLE
"CompilerThread0" daemon prio=10 tid=0x080bd000 nid=0xc63 waiting on condition [0x00000000..0xa74a88f8]
java.lang.Thread.State: RUNNABLE
"Signal Dispatcher" daemon prio=10 tid=0x080bc000 nid=0xc62 waiting on condition [0x00000000..0x00000000]
java.lang.Thread.State: RUNNABLE
"Finalizer" daemon prio=10 tid=0x080a9800 nid=0xc52 in Object.wait() [0xa752a000..0xa752b140]
java.lang.Thread.State: WAITING (on object monitor)
at java.lang.Object.wait(Native Method)
- waiting on <0xa99d0990> (a java.lang.ref.ReferenceQueue$Lock)
at java.lang.ref.ReferenceQueue.remove(ReferenceQueue .java:116)
- locked <0xa99d0990> (a java.lang.ref.ReferenceQueue$Lock)
at java.lang.ref.ReferenceQueue.remove(ReferenceQueue .java:132)
at java.lang.ref.Finalizer$FinalizerThread.run(Finali zer.java:159)
"Reference Handler" daemon prio=10 tid=0x080a8800 nid=0xc51 in Object.wait() [0xa756b000..0xa756c0c0]
java.lang.Thread.State: WAITING (on object monitor)
at java.lang.Object.wait(Native Method)
- waiting on <0xa99d00f8> (a java.lang.ref.Reference$Lock)
at java.lang.Object.wait(Object.java:485)
at java.lang.ref.Reference$ReferenceHandler.run(Refer ence.java:116)
- locked <0xa99d00f8> (a java.lang.ref.Reference$Lock)
"VM Thread" prio=10 tid=0x080a5400 nid=0xc4b runnable
"VM Periodic Task Thread" prio=10 tid=0x080c9400 nid=0xc66 waiting on condition
JNI global references: 843
Heap
def new generation total 30720K, used 19327K [0xa7880000, 0xa99d0000, 0xa99d0000)
eden space 27328K, 70% used [0xa7880000, 0xa8b5ff18, 0xa9330000)
from space 3392K, 0% used [0xa9330000, 0xa9330000, 0xa9680000)
to space 3392K, 0% used [0xa9680000, 0xa9680000, 0xa99d0000)
tenured generation total 68288K, used 21309K [0xa99d0000, 0xadc80000, 0xadc80000)
the space 68288K, 31% used [0xa99d0000, 0xaae9f668, 0xaae9f800, 0xadc80000)
compacting perm gen total 25088K, used 24886K [0xadc80000, 0xaf500000, 0xb5c80000)
the space 25088K, 99% used [0xadc80000, 0xaf4cda20, 0xaf4cdc00, 0xaf500000)
No shared spaces configured.
CompilerOracle: exclude com/zimbra/cs/session/SessionMap.putAndPrune
0 INFO [main] log - Logging to org.slf4j.impl.Log4jLoggerAdapter(org.mortbay.log) via org.mortbay.log.Slf4jLog
Zimbra server reserving server socket port=110 bindaddr=null ssl=false
Zimbra server reserving server socket port=995 bindaddr=null ssl=true
Zimbra server reserving server socket port=143 bindaddr=null ssl=false
Zimbra server reserving server socket port=993 bindaddr=null ssl=true
Zimbra server reserving server socket port=7025 bindaddr=null ssl=false
8257 INFO [main] log - Setting umask=027
8271 INFO [main] log - Setting GID=1001
8272 INFO [main] log - Setting UID=1001
8272 INFO [main] log - jetty-6.1.5
9982 INFO [main] log - No Transaction manager found - if your webapp requires one, please configure one.
Zimbra server process is running as uid=1001 euid=1001 gid=1001 egid=1001
{ empty }
{ empty }
{ empty }
Wildfire 3.1.0 [Apr 29, 2008 8:31:15 AM]
Listening on port 0.0.0.0/0.0.0.0:7335
Listening on port 0.0.0.0/0.0.0.0:5222
Listening on port 0.0.0.0/0.0.0.0:5269
-
Mailbox.log
2008-04-29 08:31:03,733 WARN [main] [] misc - LC.get(debug_disable_indexing_attachments_together ) was null, returning empty string
2008-04-29 08:31:03,734 WARN [main] [] misc - LC.get(debug_disable_redolog_fsync) was null, returning empty string
2008-04-29 08:31:03,734 WARN [main] [] misc - LC.get(debug_disable_message_store_fsync) was null, returning empty string
2008-04-29 08:31:04,058 INFO [IndexWritersSweeperThread] [] index - IndexWritersSweeperThread thread starting
2008-04-29 08:31:04,525 INFO [IndexReadersCache-Sweeper] [] IndexReadersCache - IndexReadersCache-Sweeper thread starting
2008-04-29 08:31:04,776 INFO [main] [] RedoLogManager - Starting pre-startup crash recovery
2008-04-29 08:31:05,216 INFO [main] [] RedoPlayer - No uncommitted transactions to redo
2008-04-29 08:31:05,427 INFO [main] [] RedoLogManager - Finished pre-startup crash recovery
2008-04-29 08:31:05,588 INFO [main] [] im - Setting default XMPP domain to: server.rbnmateus.com.br
2008-04-29 08:31:06,580 INFO [main] [] im - IMLocalProperties.get(connectionProvider.className ) = com.zimbra.cs.im.provider.ZimbraConnectionProvider
2008-04-29 08:31:07,081 INFO [main] [] im - IMLocalProperties.get(routingTableImpl.className) = com.zimbra.cs.im.provider.ZimbraRoutingTableImpl
2008-04-29 08:31:08,639 INFO [main] [] im - IMLocalProperties.get(provider.auth.className) = com.zimbra.cs.im.provider.ZimbraAuthProvider
2008-04-29 08:31:10,537 INFO [main] [] im - IMLocalProperties.get(provider.user.className) = com.zimbra.cs.im.provider.ZimbraUserProvider
2008-04-29 08:31:10,667 INFO [main] [] im - IMLocalProperties.get(locale) = null
2008-04-29 08:31:11,116 INFO [main] [] im - IMLocalProperties.get(provider.vcard.className) = null
2008-04-29 08:31:11,117 INFO [main] [] im - IMLocalProperties.get(network.interface) = null
2008-04-29 08:31:11,442 INFO [main] [] im - Multi User Chat domain: conference.server.rbnmateus.com.br
2008-04-29 08:31:11,738 INFO [main] [] im - IMLocalProperties.get(network.interface) = null
2008-04-29 08:31:11,801 INFO [main] [] im - IMLocalProperties.get(network.interface) = null
2008-04-29 08:31:11,802 INFO [main] [] im - Started server (unencrypted) socket on port: 5269
2008-04-29 08:31:11,803 INFO [main] [] im - IMLocalProperties.get(network.interface) = null
2008-04-29 08:31:11,804 INFO [main] [] im - Started plain (unencrypted) socket on port: 5222
2008-04-29 08:31:11,899 INFO [main] [] im - IMLocalProperties.get(network.interface) = null
2008-04-29 08:31:12,213 INFO [main] [] im - Started SSL (encrypted) socket on port: 5223
2008-04-29 08:31:12,219 WARN [main] [] im - Missing resource for key: muc.service-name in locale en_US
2008-04-29 08:31:15,193 INFO [main] [] im - Wildfire 3.1.0 [Apr 29, 2008 8:31:15 AM]
2008-04-29 08:31:20,534 INFO [main] [] lmtp - Adding LMTP callback: com.zimbra.cs.mailbox.Notification
2008-04-29 08:31:20,537 INFO [main] [] lmtp - Adding LMTP callback: com.zimbra.cs.mailbox.QuotaWarning
2008-04-29 08:31:22,205 INFO [LmtpServer] [] TcpServer/7025 - starting accept loop
2008-04-29 08:31:22,656 INFO [Pop3Server] [] TcpServer/110 - starting accept loop
2008-04-29 08:31:22,764 INFO [Pop3SSLServer] [] TcpServer/995 - starting accept loop
2008-04-29 08:31:23,554 INFO [ImapServer] [] TcpServer/143 - starting accept loop
2008-04-29 08:31:23,612 INFO [ImapSSLServer] [] TcpServer/993 - starting accept loop
2008-04-29 08:31:26,368 INFO [main] [] scheduler - Loaded 0 scheduled data source tasks
2008-04-29 08:31:26,376 INFO [main] [] purge - Starting purge thread with sleep interval 1m
2008-04-29 08:31:26,590 INFO [main] [] extensions - Post-Initializing extensions
2008-04-29 08:31:27,434 INFO [main] [] soap - Servlet AdminServlet starting up
2008-04-29 08:31:30,727 INFO [main] [] soap - Adding service AdminService to AdminServlet
2008-04-29 08:31:31,397 INFO [main] [] soap - Adding service AccountService to AdminServlet
2008-04-29 08:31:31,398 INFO [main] [] soap - Adding service MailService to AdminServlet
2008-04-29 08:31:31,400 INFO [main] [] soap - Adding service IMService to AdminServlet
2008-04-29 08:31:31,400 INFO [main] [] soap - Adding service ZimbraCertMgrService to AdminServlet
2008-04-29 08:31:31,405 INFO [main] [] soap - Adding service ZimbraLDAPUtilsService to AdminServlet
2008-04-29 08:31:31,516 INFO [main] [] ContentServlet - Servlet ContentServlet starting up
2008-04-29 08:31:31,618 INFO [main] [] account - Servlet PreAuthServlet starting up
2008-04-29 08:31:31,621 INFO [main] [] PublicICalServlet - Servlet PublicICalServlet starting up
2008-04-29 08:31:33,650 INFO [main] [] mailbox - Servlet UserServlet starting up
2008-04-29 08:31:33,878 INFO [main] [] FileUploadServlet - Servlet FileUploadServlet starting up
2008-04-29 08:31:34,039 INFO [main] [] StatsImageServlet - Servlet StatsImageServlet starting up
2008-04-29 08:31:39,590 INFO [main] [] log - No Transaction manager found - if your webapp requires one, please configure one.
2008-04-29 08:31:43,585 INFO [main] [] log - No Transaction manager found - if your webapp requires one, please configure one.
2008-04-29 08:31:44,274 INFO [main] [] log - Opened /opt/zimbra/jetty-6.1.5/logs/access_log.2008-04-29
2008-04-29 08:31:44,403 INFO [main] [] log - Started SelectChannelConnector@0.0.0.0:80
2008-04-29 08:31:44,601 INFO [main] [] log - Started SslSelectChannelConnector@0.0.0.0:7071
2008-04-29 08:31:44,632 INFO [main] [] log - Started SelectChannelConnector@0.0.0.0:7072
2008-04-29 08:33:10,528 INFO [btpool0-5] [] AuthProvider - Adding auth provider: zimbra com.zimbra.cs.service.ZimbraAuthProvider
2008-04-29 08:33:13,184 INFO [btpool0-5] [ua=zclient/5.0.4_GA_2101.DEBIAN4.0;oip=192.168.0.175;] MailboxIndex - Initialized Index for mailbox 8 directory: Lucene23Index at org.apache.lucene.store.FSDirectory@/opt/zimbra/index/0/8/index/0 Analyzer=com.zimbra.cs.index.ZimbraAnalyzer@a218a5
2008-04-29 08:33:13,184 INFO [btpool0-5] [ua=zclient/5.0.4_GA_2101.DEBIAN4.0;oip=192.168.0.175;] cache - Initializing folder and tag caches for mailbox 8
2008-04-29 08:33:13,513 INFO [IncomingDirectorySweeper] [] FileBlobStore - IncomingDirectorySweeper thread starting
2008-04-29 08:33:26,603 INFO [MailboxPurge] [name=natty@rbnmateus.com.br;mid=8;] purge - Purging messages.
2008-04-29 08:34:02,487 INFO [btpool0-0] [name=natty@rbnmateus.com.br;mid=8;ip=192.168.0.175 ;ua=ZimbraWebClient - FF2.0 (Win)/5.0.4_GA_2101.DEBIAN4.0;] SendMsg - <SendMsg> LC(mbox=3a7a93ac-120b-4dd5-83b7-74c45c52cb73, sessions=11)
2008-04-29 08:34:02,695 INFO [btpool0-0] [name=natty@rbnmateus.com.br;mid=8;ip=192.168.0.175 ;ua=ZimbraWebClient - FF2.0 (Win)/5.0.4_GA_2101.DEBIAN4.0;] JMSession - SMTP Server: server.rbnmateus.com.br
2008-04-29 08:34:03,568 INFO [btpool0-0] [name=natty@rbnmateus.com.br;mid=8;ip=192.168.0.175 ;ua=ZimbraWebClient - FF2.0 (Win)/5.0.4_GA_2101.DEBIAN4.0;] smtp - Sending message: Message-ID=<8320091.01209468843568.JavaMail.root@server>, replyType=r
2008-04-29 08:34:04,177 INFO [btpool0-0] [name=natty@rbnmateus.com.br;mid=8;ip=192.168.0.175 ;ua=ZimbraWebClient - FF2.0 (Win)/5.0.4_GA_2101.DEBIAN4.0;] zimlet - Loaded class com.zimbra.cs.zimlet.handler.NANPHandler
2008-04-29 08:34:04,186 INFO [btpool0-0] [name=natty@rbnmateus.com.br;mid=8;ip=192.168.0.175 ;ua=ZimbraWebClient - FF2.0 (Win)/5.0.4_GA_2101.DEBIAN4.0;] zimlet - Loaded class com.zimbra.cs.zimlet.handler.RegexHandler
2008-04-29 08:34:05,120 INFO [btpool0-0] [name=natty@rbnmateus.com.br;mid=8;ip=192.168.0.175 ;ua=ZimbraWebClient - FF2.0 (Win)/5.0.4_GA_2101.DEBIAN4.0;] mailbox - Added message: id=280, digest=X4V6oLZlsEozK0eFXqtEt+WohIM=, folderId=5, folderName=Sent
2008-04-29 08:34:27,277 INFO [MailboxPurge] [name=natty@rbnmateus.com.br;mid=8;] purge - Purging messages.
2008-04-29 08:34:34,265 INFO [IndexWritersSweeperThread] [] index - open index writers sweep: before=1, closed=0, after=1 (0ms)
2008-04-29 08:35:04,291 INFO [IndexWritersSweeperThread] [] index - open index writers sweep: before=1, closed=0, after=1 (0ms)
2008-04-29 08:35:32,797 INFO [btpool0-0] [name=robson@rbnmateus.com.br;ip=192.168.0.175;ua=Z imbraWebClient - IE7 (Win)/5.0.4_GA_2101.DEBIAN4.0;] session - requested session no longer exists: 14
2008-04-29 08:35:32,797 INFO [btpool0-5] [name=robson@rbnmateus.com.br;ip=192.168.0.175;ua=Z imbraWebClient - IE7 (Win)/5.0.4_GA_2101.DEBIAN4.0;] session - requested session no longer exists: 14
2008-04-29 08:35:34,638 INFO [MailboxPurge] [name=natty@rbnmateus.com.br;mid=8;] purge - Purging messages.
2008-04-29 08:35:34,640 INFO [IndexWritersSweeperThread] [] index - open index writers sweep: before=1, closed=0, after=1 (0ms)
2008-04-29 08:35:39,181 INFO [btpool0-0] [name=robson@rbnmateus.com.br;ip=192.168.0.175;ua=Z imbraWebClient - IE7 (Win)/5.0.4_GA_2101.DEBIAN4.0;] MailboxIndex - Initialized Index for mailbox 7 directory: Lucene23Index at org.apache.lucene.store.FSDirectory@/opt/zimbra/index/0/7/index/0 Analyzer=com.zimbra.cs.index.ZimbraAnalyzer@a218a5
2008-04-29 08:35:39,196 INFO [btpool0-0] [name=robson@rbnmateus.com.br;ip=192.168.0.175;ua=Z imbraWebClient - IE7 (Win)/5.0.4_GA_2101.DEBIAN4.0;] cache - Initializing folder and tag caches for mailbox 7
2008-04-29 08:36:04,685 INFO [IndexWritersSweeperThread] [] index - open index writers sweep: before=1, closed=0, after=1 (0ms)
2008-04-29 08:36:34,695 INFO [IndexWritersSweeperThread] [] index - open index writers sweep: before=1, closed=0, after=1 (0ms)
2008-04-29 08:36:41,504 INFO [MailboxPurge] [name=robson@rbnmateus.com.br;mid=7;] purge - Purging messages.
2008-04-29 08:37:04,723 INFO [IndexWritersSweeperThread] [] index - open index writers sweep: before=1, closed=0, after=1 (1ms)
2008-04-29 08:37:34,753 INFO [IndexWritersSweeperThread] [] index - open index writers sweep: before=1, closed=0, after=1 (0ms)
2008-04-29 08:37:42,043 INFO [MailboxPurge] [name=natty@rbnmateus.com.br;mid=8;] purge - Purging messages.
2008-04-29 08:38:04,779 INFO [IndexWritersSweeperThread] [] index - open index writers sweep: before=1, closed=0, after=1 (0ms)
2008-04-29 08:38:34,807 INFO [IndexWritersSweeperThread] [] index - open index writers sweep: before=1, closed=0, after=1 (0ms)
2008-04-29 08:38:42,261 INFO [MailboxPurge] [name=robson@rbnmateus.com.br;mid=7;] purge - Purging messages.
2008-04-29 08:39:04,837 INFO [IndexWritersSweeperThread] [] index - open index writers sweep: before=1, closed=0, after=1 (0ms)
2008-04-29 08:39:34,864 INFO [IndexWritersSweeperThread] [] index - open index writers sweep: before=1, closed=0, after=1 (0ms)
2008-04-29 08:39:42,594 INFO [MailboxPurge] [name=natty@rbnmateus.com.br;mid=8;] purge - Purging messages.
2008-04-29 08:40:05,183 INFO [IndexWritersSweeperThread] [] index - open index writers sweep: before=1, closed=0, after=1 (0ms)
2008-04-29 08:40:35,210 INFO [IndexWritersSweeperThread] [] index - open index writers sweep: before=1, closed=0, after=1 (0ms)
2008-04-29 08:40:42,765 INFO [MailboxPurge] [name=robson@rbnmateus.com.br;mid=7;] purge - Purging messages.
2008-04-29 08:41:05,240 INFO [IndexWritersSweeperThread] [] index - open index writers sweep: before=1, closed=0, after=1 (0ms)
2008-04-29 08:41:35,268 INFO [IndexWritersSweeperThread] [] index - open index writers sweep: before=1, closed=0, after=1 (0ms)
2008-04-29 08:41:43,129 INFO [MailboxPurge] [name=natty@rbnmateus.com.br;mid=8;] purge - Purging messages.
2008-04-29 08:42:05,294 INFO [IndexWritersSweeperThread] [] index - open index writers sweep: before=1, closed=0, after=1 (0ms)
2008-04-29 08:42:35,332 INFO [IndexWritersSweeperThread] [] index - open index writers sweep: before=1, closed=0, after=1 (0ms)
2008-04-29 08:42:43,388 INFO [MailboxPurge] [name=robson@rbnmateus.com.br;mid=7;] purge - Purging messages.
2008-04-29 08:43:05,348 INFO [IndexWritersSweeperThread] [] index - open index writers sweep: before=1, closed=0, after=1 (0ms)
2008-04-29 08:43:35,376 INFO [IndexWritersSweeperThread] [] index - open index writers sweep: before=1, closed=0, after=1 (0ms)
2008-04-29 08:43:43,606 INFO [MailboxPurge] [name=natty@rbnmateus.com.br;mid=8;] purge - Purging messages.
2008-04-29 08:44:06,395 INFO [IndexWritersSweeperThread] [] index - open index writers sweep: before=1, closed=1, after=0 (989ms)
2008-04-29 08:44:43,800 INFO [MailboxPurge] [name=robson@rbnmateus.com.br;mid=7;] purge - Purging messages.
2008-04-29 08:45:44,005 INFO [MailboxPurge] [name=natty@rbnmateus.com.br;mid=8;] purge - Purging messages.
2008-04-29 08:46:35,621 INFO [Timer-3] [] SessionCache - Removed 1 idle sessions (SOAP). 2 active sessions remain.
2008-04-29 08:46:44,219 INFO [MailboxPurge] [name=robson@rbnmateus.com.br;mid=7;] purge - Purging messages.
2008-04-29 08:47:44,339 INFO [MailboxPurge] [name=natty@rbnmateus.com.br;mid=8;] purge - Purging messages.
2008-04-29 08:48:44,539 INFO [MailboxPurge] [name=robson@rbnmateus.com.br;mid=7;] purge - Purging messages.
2008-04-29 08:49:44,726 INFO [MailboxPurge] [name=natty@rbnmateus.com.br;mid=8;] purge - Purging messages.
2008-04-29 08:50:44,882 INFO [MailboxPurge] [name=robson@rbnmateus.com.br;mid=7;] purge - Purging messages.
2008-04-29 08:51:45,010 INFO [MailboxPurge] [name=natty@rbnmateus.com.br;mid=8;] purge - Purging messages.
2008-04-29 08:52:45,140 INFO [MailboxPurge] [name=robson@rbnmateus.com.br;mid=7;] purge - Purging messages.