Missing database table after upgrade to 6.20

[linit000]

Hi all,

we did an update from 6.18 to the actual 6.20 release. Everything works quiet well, but some database updates are still pending or they were not done successfully.
We are running CentOS5.5 with mysql 5.0.77 on a ec2 amazon server. These servers have a internal IP address. Our hostname resolves to the external address. Maybe thats an issue with the database update.

SEVERE: Coding Error: OAUTH-0005 Category=8 Message=A SQL error occurred: Table 'oxdatabase_6.oauthAccounts' doesn't exist exceptionID=917629742-55858
OAUTH-0005 Category=8 Message=A SQL error occurred: Table 'oxdatabase_6.oauthAccounts' doesn't exist exceptionID=917629742-55858
	at com.openexchange.oauth.exception.OAuthExceptionFactory.createException(OAuthExceptionFactory.java:85)
	at com.openexchange.oauth.exception.OAuthExceptionFactory.createException(OAuthExceptionFactory.java:62)
	at com.openexchange.exceptions.Exceptions.create(Exceptions.java:141)
	at com.openexchange.exceptions.Exceptions.create(Exceptions.java:153)
	at com.openexchange.oauth.OAuthExceptionCodes.create(OAuthExceptionCodes.java:164)
	at com.openexchange.oauth.internal.OAuthServiceImpl.checkSecretCanDecryptStrings(OAuthServiceImpl.java:760)
	at com.openexchange.secret.recovery.impl.DefaultSecretInconsistencyDetector.isSecretWorking(DefaultSecretInconsistencyDetector.java:74)
	at sun.reflect.GeneratedMethodAccessor48.invoke(Unknown Source)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
	at java.lang.reflect.Method.invoke(Unknown Source)
	at com.openexchange.server.osgiservice.DynamicWhiteboardFactory$ServiceTrackerInvocationHandler.invoke(DynamicWhiteboardFactory.java:175)
	at $Proxy13.isSecretWorking(Unknown Source)
	at com.openexchange.secret.recovery.json.SecretRecoveryMultipleHandler.check(SecretRecoveryMultipleHandler.java:112)
	at com.openexchange.secret.recovery.json.SecretRecoveryMultipleHandler.performRequest(SecretRecoveryMultipleHandler.java:103)
	at com.openexchange.ajax.MultipleAdapterServlet.handle(MultipleAdapterServlet.java:118)
	at com.openexchange.ajax.MultipleAdapterServlet.doGet(MultipleAdapterServlet.java:88)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:596)
	at com.openexchange.ajax.AJAXServlet.service(AJAXServlet.java:413)
	at com.openexchange.ajax.SessionServlet.service(SessionServlet.java:183)
	at com.openexchange.ajax.PermissionServlet.service(PermissionServlet.java:81)
	at javax.servlet.http.HttpServlet.service(HttpServlet.java:689)
	at com.openexchange.ajp13.najp.AJPv13RequestHandlerImpl.doServletService(AJPv13RequestHandlerImpl.java:523)
	at com.openexchange.ajp13.AJPv13Request.response(AJPv13Request.java:134)
	at com.openexchange.ajp13.najp.AJPv13RequestHandlerImpl.createResponse(AJPv13RequestHandlerImpl.java:335)
	at com.openexchange.ajp13.najp.AJPv13ConnectionImpl.createResponse(AJPv13ConnectionImpl.java:227)
	at com.openexchange.ajp13.najp.AJPv13Task.call(AJPv13Task.java:352)
	at java.util.concurrent.FutureTask$Sync.innerRun(Unknown Source)
	at java.util.concurrent.FutureTask.run(Unknown Source)
	at com.openexchange.threadpool.internal.CustomThreadPoolExecutor$Worker.runTask(CustomThreadPoolExecutor.java:750)
	at com.openexchange.threadpool.internal.CustomThreadPoolExecutor$Worker.run(CustomThreadPoolExecutor.java:776)
	at java.lang.Thread.run(Unknown Source)
Caused by: com.mysql.jdbc.exceptions.jdbc4.MySQLSyntaxErrorException: Table 'oxdatabase_6.oauthAccounts' doesn't exist
	at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
	at sun.reflect.NativeConstructorAccessorImpl.newInstance(Unknown Source)
	at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(Unknown Source)
	at java.lang.reflect.Constructor.newInstance(Unknown Source)
	at com.mysql.jdbc.Util.handleNewInstance(Util.java:406)
	at com.mysql.jdbc.Util.getInstance(Util.java:381)
	at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1030)
	at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:956)
	at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3491)
	at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3423)
	at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:1936)
	at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2060)
	at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2542)
	at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:1734)
	at com.mysql.jdbc.PreparedStatement.executeQuery(PreparedStatement.java:1885)
	at com.openexchange.database.internal.wrapping.PreparedStatementWrapper.executeQuery(PreparedStatementWrapper.java:103)
	at com.openexchange.oauth.internal.OAuthServiceImpl.checkSecretCanDecryptStrings(OAuthServiceImpl.java:745)

[Martin Heiland]

Hi,

please also check the log file from the time the database updatetasks have been executed. Any stacktraces?
To get the machine going again, try to re-run the updatetask:

/opt/open-xchange/sbin/forceupdatetask -c 10 -t com.openexchange.oauth.internal.groupware.OAuthCreateTableTask

Where "10" is the context identifier here. You can also specify -n oxdatabase_schema_name when working with multiple contexts.

However, in order to find out the cause for this, log files during the DB update would be very helpful.

Greetings

[linit000]

Hi,

the forceupdate solved the problem. Maybe there where nearly two simultaneously logins at the time of the update, that caused following:

  SEVERE: UPD-0003 Category=3 Message=Update conflict detected. Another process is currently updating schema oxdatabase_6. exceptionID=1518356604-30

Attached you will find the relevant logfile.
Thanks a lot!

[881822]

Hello,

we have the same error here. After updating from 6.18 to 6.20 the table "oauthaccounts" is missing and cannot be generated by the update-procedure.

My extra-input was:

/opt/open-xchange/sbin/forceupdatetask -c 1 -t com.openexchange.oauth.internal.groupware.OAuthCre ateTableTask

The log-file open-xchange.log.o shows:

Jun 22, 2011 9:37:09 AM com.openexchange.groupware.update.internal.UpdateE xecutor runUpdates
INFO: Starting blocking updates on schema oxdatabase_6
Jun 22, 2011 9:37:09 AM com.openexchange.groupware.update.internal.UpdateE xecutor runUpdates
INFO: Starting update task OAuthCreateTableTask on schema oxdatabase_6.
Jun 22, 2011 9:37:09 AM com.openexchange.groupware.update.internal.UpdateE xecutor runUpdates
SEVERE: UPD-0014 Category=8 Message=A SQL problem occurred: Can't create table 'oxdatabase_6.oauthAccounts' (errno: 150). exceptionID=68716886-285
UPD-0014 Category=8 Message=A SQL problem occurred: Can't create table 'oxdatabase_6.oauthAccounts' (errno: 150). exceptionID=68716886-285
at com.openexchange.groupware.update.internal.UpdateE xceptionFactory.createException(UpdateExceptionFac tory.java:81)
at com.openexchange.groupware.update.internal.UpdateE xceptionFactory.createException(UpdateExceptionFac tory.java:62)
at com.openexchange.exceptions.Exceptions.create(Exce ptions.java:141)
at com.openexchange.exceptions.Exceptions.create(Exce ptions.java:153)
at com.openexchange.groupware.update.UpdateExceptionC odes.create(UpdateExceptionCodes.java:147)
at com.openexchange.oauth.internal.groupware.OAuthCre ateTableTask.perform(OAuthCreateTableTask.java:100 )
at com.openexchange.groupware.update.internal.UpdateE xecutor.runUpdates(UpdateExecutor.java:156)
at com.openexchange.groupware.update.internal.UpdateE xecutor.execute(UpdateExecutor.java:102)
at com.openexchange.groupware.update.tools.UpdateTask Toolkit.forceUpdateTask0(UpdateTaskToolkit.java:13 7)
at com.openexchange.groupware.update.tools.UpdateTask Toolkit.forceUpdateTask(UpdateTaskToolkit.java:123 )
at com.openexchange.groupware.update.tools.UpdateTask MBean.invoke(UpdateTaskMBean.java:280)
at com.sun.jmx.interceptor.DefaultMBeanServerIntercep tor.invoke(DefaultMBeanServerInterceptor.java:836)
at com.sun.jmx.mbeanserver.JmxMBeanServer.invoke(JmxM BeanServer.java:761)
at javax.management.remote.rmi.RMIConnectionImpl.doOp eration(RMIConnectionImpl.java:1427)
at javax.management.remote.rmi.RMIConnectionImpl.acce ss$200(RMIConnectionImpl.java:72)
at javax.management.remote.rmi.RMIConnectionImpl$Priv ilegedOperation.run(RMIConnectionImpl.java:1265)
at javax.management.remote.rmi.RMIConnectionImpl.doPr ivilegedOperation(RMIConnectionImpl.java:1360)
at javax.management.remote.rmi.RMIConnectionImpl.invo ke(RMIConnectionImpl.java:788)
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 sun.rmi.server.UnicastServerRef.dispatch(UnicastSe rverRef.java:305)
at sun.rmi.transport.Transport$1.run(Transport.java:1 59)
at java.security.AccessController.doPrivileged(Native Method)
at sun.rmi.transport.Transport.serviceCall(Transport. java:155)
at sun.rmi.transport.tcp.TCPTransport.handleMessages( TCPTransport.java:535)
at sun.rmi.transport.tcp.TCPTransport$ConnectionHandl er.run0(TCPTransport.java:790)
at sun.rmi.transport.tcp.TCPTransport$ConnectionHandl er.run(TCPTransport.java:649)
at java.util.concurrent.ThreadPoolExecutor$Worker.run Task(ThreadPoolExecutor.java:886)
at java.util.concurrent.ThreadPoolExecutor$Worker.run (ThreadPoolExecutor.java:908)
at java.lang.Thread.run(Thread.java:662)
Caused by: java.sql.SQLException: Can't create table 'oxdatabase_6.oauthAccounts' (errno: 150)
at com.mysql.jdbc.SQLError.createSQLException(SQLErro r.java:1055)
at com.mysql.jdbc.SQLError.createSQLException(SQLErro r.java:956)
at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.ja va:3491)
at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.ja va:3423)
at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:19 36)
at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java :2060)
at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionIm pl.java:2542)
at com.mysql.jdbc.PreparedStatement.executeInternal(P reparedStatement.java:1734)
at com.mysql.jdbc.PreparedStatement.executeUpdate(Pre paredStatement.java:2019)
at com.mysql.jdbc.PreparedStatement.executeUpdate(Pre paredStatement.java:1937)
at com.mysql.jdbc.PreparedStatement.executeUpdate(Pre paredStatement.java:1922)
at com.openexchange.database.internal.wrapping.JDBC3P reparedStatementWrapper.executeUpdate(JDBC3Prepare dStatementWrapper.java:120)
at com.openexchange.oauth.internal.groupware.OAuthCre ateTableTask.perform(OAuthCreateTableTask.java:98)
... 26 more
Jun 22, 2011 9:37:09 AM com.openexchange.groupware.update.internal.UpdateE xecutor runUpdates
INFO: Update task OAuthCreateTableTask on schema oxdatabase_6 failed.
Jun 22, 2011 9:37:09 AM com.openexchange.groupware.update.internal.UpdateE xecutor runUpdates
INFO: Finished blocking updates on schema oxdatabase_6

Any suggestions?

[Martin Heiland]

Hi,

please open a bug report for this over at https://bugs.open-xchange.com/. There we can track and assign the issue to several developers.

Thanks!