[SOLVED] Upgrading from 6.09 to 6.10 fails

[lytledd]

Hey everybody,

Maybe someone can give me a few pointers. I'm currently running 6.09 FOSS edition, under VirtualBox 3.2.12. 64BIT edition. The host is running Mandriva 2010.1 64BIT, on a Core2Quad with 8GB memory for the VMs.

The guest is running Ubuntu 8.04 64BIT LTS, 4GB memory and 2 CPUs. Split DNS is running under a 2nd virtual machine. I've been running under this setup since 6.07.

In the zmsetup logs, the following two errors stand out:

Sat Jan 1 07:23:41 2011 *** Running as root user: /opt/zimbra/bin/zmcertmgr deployca -localonly
** Importing CA /opt/zimbra/ssl/zimbra/ca/ca.pem into CACERTS...done.
** Copying CA to /opt/zimbra/conf/ca...done.
unable to load certificate
1321:error:0906D06C:PEM routines:PEM_read_bio:no start lineem_lib.c:650:Expecting: TRUSTED CERTIFICATE
Sat Jan 1 07:23:43 2011 done.
Sat Jan 1 07:23:43 2011 *** Running as root user: /opt/zimbra/bin/zmcertmgr verifycrt comm > /dev/null 2>&1
** Verifying /opt/zimbra/ssl/zimbra/commercial/commercial.crt against /opt/zimbra/ssl/zimbra/commercial/commercial.key
XXXXX ERROR: Can't find private key /opt/zimbra/ssl/zimbra/commercial/commercial.key
Sat Jan 1 07:23:44 2011 *** Running as root user: /opt/zimbra/bin/zmcertmgr verifycrt self > /dev/null 2>&1
** Verifying /opt/zimbra/ssl/zimbra/server/server.crt against /opt/zimbra/ssl/zimbra/server/server.key
Certificate (/opt/zimbra/ssl/zimbra/server/server.crt) and private key (/opt/zimbra/ssl/zimbra/server/server.key) match.
Valid Certificate: /opt/zimbra/ssl/zimbra/server/server.crt: OK

And the error:

Sat Jan 1 07:33:40 2011 *** Running as zimbra user: /opt/zimbra/bin/zmprov ut -h web.drdos.info /opt/zimbra/wiki/Template
[] INFO: I/O exception (java.net.ConnectException) caught when processing request: Connection refused
[] INFO: Retrying request
ERROR: zclient.IO_ERROR (invoke Connection refused, server: localhost) (cause: java.net.ConnectException Connection refused)
Sat Jan 1 07:33:44 2011 failed.
Sat Jan 1 07:33:44 2011 Restarting mailboxd...
Sat Jan 1 07:33:44 2011 *** Running as zimbra user: /opt/zimbra/bin/zmmailboxdctl restart
Stopping mailboxd...mailboxd is not running.
Starting mailboxd...done.

After the upgrade completes, doing a zmcontrol status shows everything up and running, but a few seconds later mailbox isn't running. I have the zmmailboxd.out with a portion of the entry below:

50715 FATAL [main] system - Unable to initialize servlet ExtensionDispatcherServlet; halting
javax.servlet.ServletException: Invalid port number "%%zimbraMailPort%%" in allowed.ports parameter

I've reverted to the previous snapshot to get things up and running again (I do love that)

Any suggestions? I can supply more information upon request.

Thanks!

Doug

[phoenix]

I'll move this to the correct forum as it's not a question about the Zimbra Appliance. It would appear from the log snippet that your certificate has a problem, has it expired?

[lytledd]

I'll move this to the correct forum as it's not a question about the Zimbra Appliance.

Hi Bill,

Thanks for taking the time. I put it in the virtualization forum because it also include in the description:

or any other virtual machine software?

Anyways, no the certs expire in 2020 (They're self-signed):

Certificate for Zimbra mailboxd Service:
Subject: /C=US/ST=Michigan/O=web.drdos.info/OU=web.drdos.info/CN=web.drdos.info
Issuer: /C=US/ST=N/A/L=N/A/O=Zimbra Collaboration Suite/OU=Zimbra Collaboration Suite/CN=web.drdos.info
Validation Days: Nov 20 10:54:47 2010 GMT - Nov 17 10:54:47 2020 GMT
Subject Alternative Name: web.drdos.info, drdos.info, web.drkathiewhite.com, web.pf01.com

Doug

[lytledd]

Just an update on this thread. The issues I had were resolved with the upgrade of VirtualBox from version 3 to version 4.

Doug

[dgamez]

Hi Bill,

I have a similar issue,

I'm running:
Release 7.2.0_GA_2669.RHEL5_64_20120410001942 RHEL5_64 NETWORK edition, Patch 7.2.0_P1.
KVM as Virtual Host
Intel QEMU Virtual CPU x 2 (version 1.0.50)
RAM 4051752 kB

Host host.domain.com
antispam Running
antivirus Running
convertd Running
ldap Running
logger Running
mailbox Stopped
zmmailboxdctl is not running.
mta Running
snmp Running
spell Running
stats Running

/var/log/zimbra.log

zmmailboxdmgr[21870]: file /opt/zimbra/log/zmmailboxd.pid does not exist
zmmailboxdmgr[21870]: assuming no other instance is running
zmmailboxdmgr[21870]: no manager process is running
zmmailboxdmgr[22302]: stale pid 19882 found in /opt/zimbra/log/zmmailboxd_manager.pid: No such process



/opt/zimbra/log/zmmailboxd.out

CompilerOracle: exclude com/zimbra/cs/session/SessionMap.putAndPrune
CompilerOracle: exclude com/zimbra/cs/mailbox/MailItem.delete
CompilerOracle: exclude org/apache/xerces/impl/XMLDocumentFragmentScannerImpl$FragmentContentDisp atcher.dispatch
0 INFO [main] log - Logging to org.slf4j.impl.Log4jLoggerAdapter(org.mortbay.log) via org.mortbay.log.Slf4jLog
Total time for which application threads were stopped: 0.0001110 seconds
Total time for which application threads were stopped: 0.0000950 seconds
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
JettyMonitor monitoring thread pool org.mortbay.thread.BoundedThreadPool@56609959
1381 INFO [main] log - Setting umask=027
1382 INFO [main] log - Current rlimit_nofiles (soft=524288, hard=524288)
1382 INFO [main] log - Set rlimit_nofiles (soft=65536, hard=65536)
1383 INFO [main] log - Setting GID=500
1383 INFO [main] log - Setting UID=500
1383 INFO [main] log - jetty-6.1.22.z6
Total time for which application threads were stopped: 0.0000970 seconds
1524 INFO [main] log - No Transaction manager found - if your webapp requires one, please configure one.
### Excluding compile: org.apache.xerces.impl.XMLDocumentFragmentScannerI mpl$FragmentContentDispatcher::dispatch
Zimbra server process is running as uid=500 euid=500 gid=500 egid=500
2085 FATAL [main] system - Unable to initialize servlet ExtensionDispatcherServlet; halting
javax.servlet.ServletException: Invalid port number "%%zimbraMailPort%%" in allowed.ports parameter
at com.zimbra.cs.servlet.ZimbraServlet.init(ZimbraSer vlet.java:136)
at javax.servlet.GenericServlet.init(GenericServlet.j ava:241)
at org.mortbay.jetty.servlet.ServletHolder.initServle t(ServletHolder.java:440)
at org.mortbay.jetty.servlet.ServletHolder.doStart(Se rvletHolder.java:263)
at org.mortbay.component.AbstractLifeCycle.start(Abst ractLifeCycle.java:50)
at org.mortbay.jetty.servlet.ServletHandler.initializ e(ServletHandler.java:685)
at org.mortbay.jetty.servlet.Context.startContext(Con text.java:140)
at org.mortbay.jetty.webapp.WebAppContext.startContex t(WebAppContext.java:1254)
at org.mortbay.jetty.handler.ContextHandler.doStart(C ontextHandler.java:517)
at org.mortbay.jetty.webapp.WebAppContext.doStart(Web AppContext.java:471)
at org.mortbay.component.AbstractLifeCycle.start(Abst ractLifeCycle.java:50)
at org.mortbay.jetty.handler.HandlerCollection.doStar t(HandlerCollection.java:152)
at org.mortbay.jetty.handler.ContextHandlerCollection .doStart(ContextHandlerCollection.java:156)
at org.mortbay.component.AbstractLifeCycle.start(Abst ractLifeCycle.java:50)
at org.mortbay.jetty.handler.HandlerCollection.doStar t(HandlerCollection.java:152)
at org.mortbay.component.AbstractLifeCycle.start(Abst ractLifeCycle.java:50)
at org.mortbay.jetty.handler.HandlerWrapper.doStart(H andlerWrapper.java:130)
at org.mortbay.component.AbstractLifeCycle.start(Abst ractLifeCycle.java:50)
at org.mortbay.jetty.handler.HandlerWrapper.doStart(H andlerWrapper.java:130)
at org.mortbay.jetty.handler.DebugHandler.doStart(Deb ugHandler.java:127)
at org.mortbay.component.AbstractLifeCycle.start(Abst ractLifeCycle.java:50)
at org.mortbay.jetty.handler.HandlerWrapper.doStart(H andlerWrapper.java:130)
at org.mortbay.jetty.Server.doStart(Server.java:224)
at org.mortbay.setuid.SetUIDServer.doStart(SetUIDServ er.java:158)
at org.mortbay.component.AbstractLifeCycle.start(Abst ractLifeCycle.java:50)
at org.mortbay.xml.XmlConfiguration.main(XmlConfigura tion.java:985)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Nativ e Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(Native MethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(De legatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.mortbay.start.Main.invokeMain(Main.java:194)
at org.mortbay.start.Main.start(Main.java:534)
at org.mortbay.start.Main.start(Main.java:441)
at org.mortbay.start.Main.main(Main.java:119)
Heap
par new generation total 82944K, used 43906K [0x00000000dbe00000, 0x00000000e1800000, 0x00000000e1800000)
eden space 73728K, 59% used [0x00000000dbe00000, 0x00000000de8e08f0, 0x00000000e0600000)
from space 9216K, 0% used [0x00000000e0600000, 0x00000000e0600000, 0x00000000e0f00000)
to space 9216K, 0% used [0x00000000e0f00000, 0x00000000e0f00000, 0x00000000e1800000)
concurrent mark-sweep generation total 368640K, used 0K [0x00000000e1800000, 0x00000000f8000000, 0x00000000f8000000)
concurrent-mark-sweep perm gen total 131072K, used 17264K [0x00000000f8000000, 0x0000000100000000, 0x0000000100000000)
CompilerOracle: exclude com/zimbra/cs/session/SessionMap.putAndPrune
CompilerOracle: exclude com/zimbra/cs/mailbox/MailItem.delete
CompilerOracle: exclude org/apache/xerces/impl/XMLDocumentFragmentScannerImpl$FragmentContentDisp atcher.dispatch
Total time for which application threads were stopped: 0.0000690 seconds
0 INFO [main] log - Logging to org.slf4j.impl.Log4jLoggerAdapter(org.mortbay.log) via org.mortbay.log.Slf4jLog
Total time for which application threads were stopped: 0.0000930 seconds
Total time for which application threads were stopped: 0.0001310 seconds
Zimbra server reserving server socket port=110 bindaddr=null ssl=false
Zimbra server reserving server socket port=995 bindaddr=null ssl=true
Total time for which application threads were stopped: 0.0000970 seconds
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
JettyMonitor monitoring thread pool org.mortbay.thread.BoundedThreadPool@709446e4
2485 INFO [main] log - Setting umask=027
2486 INFO [main] log - Current rlimit_nofiles (soft=524288, hard=524288)
2486 INFO [main] log - Set rlimit_nofiles (soft=65536, hard=65536)
2486 INFO [main] log - Setting GID=500
2487 INFO [main] log - Setting UID=500
2586 INFO [main] log - jetty-6.1.22.z6
Total time for which application threads were stopped: 0.0181100 seconds
3003 INFO [main] log - No Transaction manager found - if your webapp requires one, please configure one.
### Excluding compile: org.apache.xerces.impl.XMLDocumentFragmentScannerI mpl$FragmentContentDispatcher::dispatch
Total time for which application threads were stopped: 0.0003620 seconds
Total time for which application threads were stopped: 0.0003280 seconds
Zimbra server process is running as uid=500 euid=500 gid=500 egid=500
Total time for which application threads were stopped: 0.0003440 seconds
14732 FATAL [main] system - Unable to initialize servlet ExtensionDispatcherServlet; halting
javax.servlet.ServletException: Invalid port number "%%zimbraMailPort%%" in allowed.ports parameter
at com.zimbra.cs.servlet.ZimbraServlet.init(ZimbraSer vlet.java:136)
at javax.servlet.GenericServlet.init(GenericServlet.j ava:241)
at org.mortbay.jetty.servlet.ServletHolder.initServle t(ServletHolder.java:440)
at org.mortbay.jetty.servlet.ServletHolder.doStart(Se rvletHolder.java:263)
at org.mortbay.component.AbstractLifeCycle.start(Abst ractLifeCycle.java:50)
at org.mortbay.jetty.servlet.ServletHandler.initializ e(ServletHandler.java:685)
at org.mortbay.jetty.servlet.Context.startContext(Con text.java:140)
at org.mortbay.jetty.webapp.WebAppContext.startContex t(WebAppContext.java:1254)
at org.mortbay.jetty.handler.ContextHandler.doStart(C ontextHandler.java:517)
at org.mortbay.jetty.webapp.WebAppContext.doStart(Web AppContext.java:471)
at org.mortbay.component.AbstractLifeCycle.start(Abst ractLifeCycle.java:50)
at org.mortbay.jetty.handler.HandlerCollection.doStar t(HandlerCollection.java:152)
at org.mortbay.jetty.handler.ContextHandlerCollection .doStart(ContextHandlerCollection.java:156)
at org.mortbay.component.AbstractLifeCycle.start(Abst ractLifeCycle.java:50)
at org.mortbay.jetty.handler.HandlerCollection.doStar t(HandlerCollection.java:152)
at org.mortbay.component.AbstractLifeCycle.start(Abst ractLifeCycle.java:50)
at org.mortbay.jetty.handler.HandlerWrapper.doStart(H andlerWrapper.java:130)
at org.mortbay.component.AbstractLifeCycle.start(Abst ractLifeCycle.java:50)
at org.mortbay.jetty.handler.HandlerWrapper.doStart(H andlerWrapper.java:130)
at org.mortbay.jetty.handler.DebugHandler.doStart(Deb ugHandler.java:127)
at org.mortbay.component.AbstractLifeCycle.start(Abst ractLifeCycle.java:50)
at org.mortbay.jetty.handler.HandlerWrapper.doStart(H andlerWrapper.java:130)
at org.mortbay.jetty.Server.doStart(Server.java:224)
at org.mortbay.setuid.SetUIDServer.doStart(SetUIDServ er.java:158)
at org.mortbay.component.AbstractLifeCycle.start(Abst ractLifeCycle.java:50)
at org.mortbay.xml.XmlConfiguration.main(XmlConfigura tion.java:985)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Nativ e Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(Native MethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(De legatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
at org.mortbay.start.Main.invokeMain(Main.java:194)
at org.mortbay.start.Main.start(Main.java:534)
at org.mortbay.start.Main.start(Main.java:441)
at org.mortbay.start.Main.main(Main.java:119)
Heap
par new generation total 82944K, used 45380K [0x00000000dbe00000, 0x00000000e1800000, 0x00000000e1800000)
eden space 73728K, 61% used [0x00000000dbe00000, 0x00000000dea513c0, 0x00000000e0600000)
from space 9216K, 0% used [0x00000000e0600000, 0x00000000e0600000, 0x00000000e0f00000)
to space 9216K, 0% used [0x00000000e0f00000, 0x00000000e0f00000, 0x00000000e1800000)
concurrent mark-sweep generation total 368640K, used 0K [0x00000000e1800000, 0x00000000f8000000, 0x00000000f8000000)
concurrent-mark-sweep perm gen total 131072K, used 17264K [0x00000000f8000000, 0x0000000100000000, 0x0000000100000000)