Mailbox and logger not starting - SLES9 M3 Beta436

[anand]

time to start (not generally recommended) editing your mysqld_safe script. (Save away the original, first!)

Can you add "strace -f -o /tmp/mysql.strace" to where mysqld is launched by the mysqld_safe script? Not a sure fire solution, but might reveal something interesting.

[prosenbl]

Originally Posted by anand

time to start (not generally recommended) editing your mysqld_safe script. (Save away the original, first!)

Can you add "strace -f -o /tmp/mysql.strace" to where mysqld is launched by the mysqld_safe script? Not a sure fire solution, but might reveal something interesting.

Didn't get the chance to try this, as I was able to get everything working. I manually edited the my.cnf generated by zmmyinit, and the my.logger.cnf and changed bind-address to 127.0.0.1 instead of localhost.

Then, I commented out the zmmycnf from zmmyinit, and added '--force' after the '--defaults-file' argument of ./scripts/mysql_install_db command in both zmmyinit and zmloggerinit.

After running the zmmyinit and zmloggerinit scripts with those changes, everything is working fine.

Thanks for all the help, Zimbra is a great product that I look forward to working with.

[DanielP]

Hi,

Thank you very much. After changing from localhost to ipadress in my.cnf and logger.cnf my zimbra starts up again.(after installation everything worked, but stopped after a reboot).

Zimbra is back up and rocking

/D

[JohnPeacock]

AFAICT, the most appropriate fix is to change the stub my.cnf file so that networking is never loaded (this is also the most secure option):

--- /opt/zimbra/bin/zmmycnf.orig        2006-01-24 16:14:46.885149824 -0500
+++ /opt/zimbra/bin/zmmycnf     2006-01-24 16:10:40.744679120 -0500
@@ -78,9 +78,10 @@
 datadir      = ${mysql_data_directory}
 socket       = ${mysql_socket}
 pid-file     = ${mysql_pidfile}
-bind-address = ${mysql_bind_address}
+#bind-address = ${mysql_bind_address}
 port         = ${mysql_port}

+skip-networking
 skip-external-locking

 log-slow-queries = ${zimbra_log_directory}/myslow.log

Once that is done, going through and deleting the db files and rerunning /opt/zimbra/libexec/zmmyinit works fine.

HTH

John

[echristi]

I started out with the same problem as everyone else in this thread, and have a new problem. I have follwed the steps above by prosenbl, and my mailbox still isn't starting (as well as my logger). The mysql database got created finally, so I am not sure what is wrong. Any more suggestions? Thanks!!! (I'm getting close, I can feel it!)

My /opt/zimbra/log/mysql.log file looks like (where it installed, I shutdown, and tried restarting):

060126 13:15:09 mysqld started
InnoDB: The first specified data file ./ibdata1 did not exist:
InnoDB: a new database to be created!
060126 13:15:09 InnoDB: Setting file ./ibdata1 size to 10 MB
InnoDB: Database physically writes the file full: wait...
060126 13:15:10 InnoDB: Log file ./ib_logfile0 did not exist: new to be created
InnoDB: Setting log file ./ib_logfile0 size to 100 MB
InnoDB: Database physically writes the file full: wait...
InnoDB: Progress in MB: 100
060126 13:15:13 InnoDB: Log file ./ib_logfile1 did not exist: new to be created
InnoDB: Setting log file ./ib_logfile1 size to 100 MB
InnoDB: Database physically writes the file full: wait...
InnoDB: Progress in MB: 100
InnoDB: Doublewrite buffer not found: creating new
InnoDB: Doublewrite buffer created
InnoDB: Creating foreign key constraint system tables
InnoDB: Foreign key constraint system tables created
060126 13:15:16 InnoDB: Started; log sequence number 0 0
/opt/zimbra/mysql/bin/mysqld: ready for connections.
Version: '4.1.10a-standard-log' socket: '/opt/zimbra/db/mysql.sock' port: 7306 MySQL Community Edition - Standard (GPL)


060126 13:16:29 [Note] /opt/zimbra/mysql/bin/mysqld: Normal shutdown

060126 13:16:29 InnoDB: Starting shutdown...
060126 13:16:33 InnoDB: Shutdown completed; log sequence number 0 77848
060126 13:16:33 [Note] /opt/zimbra/mysql/bin/mysqld: Shutdown complete

060126 13:16:33 mysqld ended



060126 13:18:14 mysqld started
060126 13:18:19 InnoDB: Started; log sequence number 0 77848
/opt/zimbra/mysql/bin/mysqld: ready for connections.
Version: '4.1.10a-standard-log' socket: '/opt/zimbra/db/mysql.sock' port: 7306 MySQL Community Edition - Standard (GPL)

[marcmac]

sounds like /opt/zimbra/libexec/zmmyinit failed. Try rerunning that (stop sql, first). Should be run as zimbra.

[echristi]

I did that, and it did not help. Mysql is starting fine. I notice a tomcat process start, but it goes away while zmcontrol start is being performed.

Running sh -x tomcat start does not produce any errors, and catalina.out has the following:

zmtomcatstart: info: stale pid 14249 in pid file: No such process
Jan 27, 2006 5:38:58 AM org.apache.coyote.http11.Http11Protocol init
INFO: Initializing Coyote HTTP/1.1 on http-443
Jan 27, 2006 5:38:58 AM org.apache.coyote.http11.Http11Protocol init
INFO: Initializing Coyote HTTP/1.1 on http-7071
Jan 27, 2006 5:38:58 AM org.apache.catalina.startup.Catalina load
INFO: Initialization processed in 4373 ms
Jan 27, 2006 5:38:58 AM org.apache.catalina.core.StandardService start
INFO: Starting service Catalina
Jan 27, 2006 5:38:58 AM org.apache.catalina.core.StandardEngine start
INFO: Starting Servlet Engine: Apache Tomcat/5.5.7
Jan 27, 2006 5:38:58 AM org.apache.catalina.core.StandardHost start
INFO: XML validation disabled
log4j:WARN No appenders could be found for logger (org.apache.catalina.session.ManagerBase).
log4j:WARN Please initialize the log4j system properly.
Zimbra server reserving server socket port=110 bindaddr=null
Zimbra server reserving server socket port=995 bindaddr=null
Zimbra server reserving server socket port=143 bindaddr=null
Zimbra server reserving server socket port=993 bindaddr=null
Zimbra server process is running as root, changing to user=zimbra uid=1002 gid=1000
Zimbra server process, after change, is running with uid=1002 euid=1002 gid=1000 egid=1000

I have updated to the latest version of the jdk, 1.5.06.

[marcmac]

What's in /opt/zimbra/log/zimbra.log after tomcat shuts itself down?

[echristi]

Here is my zimbra.log....

mysql is running, and I can access it as the zimbra user... what should I look for?


2006-01-27 07:02:59,498 INFO [main] [] TransformationServer/7047 - Convertd is ready
2006-01-27 07:02:59,503 INFO [Convertd] [] TcpServer/7047 - starting accept loop
2006-01-27 07:03:09,252 INFO [main] [] soap - Servlet SoapServlet starting up
2006-01-27 07:03:09,312 INFO [main] [] soap - Adding service AccountService to SoapServlet
2006-01-27 07:03:09,443 INFO [main] [] soap - Adding service MailService to SoapServlet
2006-01-27 07:03:09,986 INFO [main] [] soap - Adding service IMService to SoapServlet
2006-01-27 07:03:09,995 INFO [main] [] misc - version=3.0.0_M4_62.RHEL4 release=20060124112014 builddate=20060124-1120 buildhost=build.liquidsys.com
2006-01-27 07:03:09,999 INFO [main] [] misc - LANG environment is set to: C
2006-01-27 07:03:10,113 INFO [main] [] DbPool - Setting mysql connector property: maxActive=10
2006-01-27 07:03:10,114 INFO [main] [] misc - Setting mysql connection pool size to 10
2006-01-27 07:03:15,034 FATAL [main] [] system - Config initialization failed
com.zimbra.cs.service.ServiceException: system failure: getting database connection
at com.zimbra.cs.service.ServiceException.FAILURE(Ser viceException.java:131)
at com.zimbra.cs.db.DbPool.getConnection(DbPool.java: 281)
at com.zimbra.cs.util.Config.init(Config.java:69)
at com.zimbra.cs.util.Config.initConfig(Config.java:9 6)
at com.zimbra.cs.util.Config.getString(Config.java:10 8)
at com.zimbra.cs.db.Versions.checkDBVersion(Versions. java:87)
at com.zimbra.cs.db.Versions.checkVersions(Versions.j ava:82)
at com.zimbra.cs.util.Zimbra.startup(Zimbra.java:89)
at com.zimbra.soap.SoapServlet.init(SoapServlet.java: 118)
at javax.servlet.GenericServlet.init(GenericServlet.j ava:211)
at org.apache.catalina.core.StandardWrapper.loadServl et(StandardWrapper.java:1068)
at org.apache.catalina.core.StandardWrapper.load(Stan dardWrapper.java:900)
at org.apache.catalina.core.StandardContext.loadOnSta rtup(StandardContext.java:3823)
at org.apache.catalina.core.StandardContext.start(Sta ndardContext.java:4087)
at org.apache.catalina.core.ContainerBase.addChildInt ernal(ContainerBase.java:759)
at org.apache.catalina.core.ContainerBase.addChild(Co ntainerBase.java:739)
at org.apache.catalina.core.StandardHost.addChild(Sta ndardHost.java:524)
at org.apache.catalina.startup.HostConfig.deployDirec tory(HostConfig.java:909)
at org.apache.catalina.startup.HostConfig.deployDirec tories(HostConfig.java:872)
at org.apache.catalina.startup.HostConfig.deployApps( HostConfig.java:474)
at org.apache.catalina.startup.HostConfig.start(HostC onfig.java:1106)
at org.apache.catalina.startup.HostConfig.lifecycleEv ent(HostConfig.java:310)
at org.apache.catalina.util.LifecycleSupport.fireLife cycleEvent(LifecycleSupport.java:119)
at org.apache.catalina.core.ContainerBase.start(Conta inerBase.java:1019)
at org.apache.catalina.core.StandardHost.start(Standa rdHost.java:718)
at org.apache.catalina.core.ContainerBase.start(Conta inerBase.java:1011)
at org.apache.catalina.core.StandardEngine.start(Stan dardEngine.java:440)
at org.apache.catalina.core.StandardService.start(Sta ndardService.java:450)
at org.apache.catalina.core.StandardServer.start(Stan dardServer.java:683)
at org.apache.catalina.startup.Catalina.start(Catalin a.java:537)
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:585)
at org.apache.catalina.startup.Bootstrap.start(Bootst rap.java:271)
at org.apache.catalina.startup.Bootstrap.main(Bootstr ap.java:409)
Caused by: java.sql.SQLException: Server connection failure during transaction. Due to underlying exception: 'java.net.SocketException: java.net.ConnectException: Connection refused'.

** BEGIN NESTED EXCEPTION **

java.net.SocketException
MESSAGE: java.net.ConnectException: Connection refused

STACKTRACE:

java.net.SocketException: java.net.ConnectException: Connection refused
at com.mysql.jdbc.StandardSocketFactory.connect(Stand ardSocketFactory.java:156)
at com.mysql.jdbc.MysqlIO.(MysqlIO.java:284)
at com.mysql.jdbc.Connection.createNewIO(Connection.j ava:2680)
at com.mysql.jdbc.Connection.(Connection.java:1485)
at com.mysql.jdbc.NonRegisteringDriver.connect(NonReg isteringDriver.java:266)
at java.sql.DriverManager.getConnection(DriverManager .java:525)
at java.sql.DriverManager.getConnection(DriverManager .java:140)
at org.apache.commons.dbcp.DriverManagerConnectionFac tory.createConnection(DriverManagerConnectionFacto ry.java:97)
at org.apache.commons.dbcp.PoolableConnectionFactory. makeObject(PoolableConnectionFactory.java:300)
at org.apache.commons.pool.impl.GenericObjectPool.bor rowObject(GenericObjectPool.java:816)
at org.apache.commons.dbcp.PoolingDriver.connect(Pool ingDriver.java:171)
at java.sql.DriverManager.getConnection(DriverManager .java:525)
at java.sql.DriverManager.getConnection(DriverManager .java:193)
at com.zimbra.cs.db.DbPool.getConnection(DbPool.java: 272)
at com.zimbra.cs.util.Config.init(Config.java:69)
at com.zimbra.cs.util.Config.initConfig(Config.java:9 6)
at com.zimbra.cs.util.Config.getString(Config.java:10 8)
at com.zimbra.cs.db.Versions.checkDBVersion(Versions. java:87)
at com.zimbra.cs.db.Versions.checkVersions(Versions.j ava:82)
at com.zimbra.cs.util.Zimbra.startup(Zimbra.java:89)
at com.zimbra.soap.SoapServlet.init(SoapServlet.java: 118)
at javax.servlet.GenericServlet.init(GenericServlet.j ava:211)
at org.apache.catalina.core.StandardWrapper.loadServl et(StandardWrapper.java:1068)
at org.apache.catalina.core.StandardWrapper.load(Stan dardWrapper.java:900)
at org.apache.catalina.core.StandardContext.loadOnSta rtup(StandardContext.java:3823)
at org.apache.catalina.core.StandardContext.start(Sta ndardContext.java:4087)
at org.apache.catalina.core.ContainerBase.addChildInt ernal(ContainerBase.java:759)
at org.apache.catalina.core.ContainerBase.addChild(Co ntainerBase.java:739)
at org.apache.catalina.core.StandardHost.addChild(Sta ndardHost.java:524)
at org.apache.catalina.startup.HostConfig.deployDirec tory(HostConfig.java:909)
at org.apache.catalina.startup.HostConfig.deployDirec tories(HostConfig.java:872)
at org.apache.catalina.startup.HostConfig.deployApps( HostConfig.java:474)
at org.apache.catalina.startup.HostConfig.start(HostC onfig.java:1106)
at org.apache.catalina.startup.HostConfig.lifecycleEv ent(HostConfig.java:310)
at org.apache.catalina.util.LifecycleSupport.fireLife cycleEvent(LifecycleSupport.java:119)
at org.apache.catalina.core.ContainerBase.start(Conta inerBase.java:1019)
at org.apache.catalina.core.StandardHost.start(Standa rdHost.java:718)
at org.apache.catalina.core.ContainerBase.start(Conta inerBase.java:1011)
at org.apache.catalina.core.StandardEngine.start(Stan dardEngine.java:440)
at org.apache.catalina.core.StandardService.start(Sta ndardService.java:450)
at org.apache.catalina.core.StandardServer.start(Stan dardServer.java:683)
at org.apache.catalina.startup.Catalina.start(Catalin a.java:537)
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:585)
at org.apache.catalina.startup.Bootstrap.start(Bootst rap.java:271)
at org.apache.catalina.startup.Bootstrap.main(Bootstr ap.java:409)


** END NESTED EXCEPTION **


Attempted reconnect 3 times. Giving up.
at com.mysql.jdbc.Connection.createNewIO(Connection.j ava:2749)
at com.mysql.jdbc.Connection.(Connection.java:1485)
at com.mysql.jdbc.NonRegisteringDriver.connect(NonReg isteringDriver.java:266)
at java.sql.DriverManager.getConnection(DriverManager .java:525)
at java.sql.DriverManager.getConnection(DriverManager .java:140)
at org.apache.commons.dbcp.DriverManagerConnectionFac tory.createConnection(DriverManagerConnectionFacto ry.java:97)
at org.apache.commons.dbcp.PoolableConnectionFactory. makeObject(PoolableConnectionFactory.java:300)
at org.apache.commons.pool.impl.GenericObjectPool.bor rowObject(GenericObjectPool.java:816)
at org.apache.commons.dbcp.PoolingDriver.connect(Pool ingDriver.java:171)
at java.sql.DriverManager.getConnection(DriverManager .java:525)
at java.sql.DriverManager.getConnection(DriverManager .java:193)
at com.zimbra.cs.db.DbPool.getConnection(DbPool.java: 272)
... 34 more

[marcmac]

How exactly are you accessing sql? Tomcat is not getting to it, that's why it won't start.

What does
zmlocalconfig | grep sql
return?