Homepage | Products | OX Knowledge Base | Support | Try Now | Contact | Company
OX Logo
Page 1 of 2 12 LastLast
Results 1 to 10 of 16
  1. #1
    Join Date
    Feb 2012
    Posts
    8

    Default Update To 6.20.1 problems - Error: Updating database ... Try again later.

    I have open-xchange installed in a Debian Squeze. After upgrade to versions 6.20.1 rev 5 the logins appears but user can not login.

    The error in login display is Error: Updating database ... Try again later.

    After waiting one day the problem persists. the database in the mysql data folder have 1.7 MB

    The open-xchange.log, when a user try to make login, is:

    SEVERE: Logged at: com.openexchange.ajax.Login.loginOperation(Login.j ava:944)
    CTX-0007 Category=4 Message=Updating database ... Try again later. exceptionID=2053721904-41
    com.openexchange.ajp13.remoteAddress=127.0.0.1
    com.openexchange.ajp13.remotePort=34639
    CTX-0007 Category=4 Message=Updating database ... Try again later. exceptionID=2053721904-41
    at com.openexchange.login.internal.LoginPerformer.doL ogin(LoginPerformer.java:217)
    at com.openexchange.login.internal.LoginPerformer.doL ogin(LoginPerformer.java:128)
    at com.openexchange.ajax.Login$11.doLogin(Login.java: 904)
    at com.openexchange.ajax.Login.loginOperation(Login.j ava:919)
    at com.openexchange.ajax.Login.doLogin(Login.java:901 )
    at com.openexchange.ajax.Login$1.handleRequest(Login. java:184)
    at com.openexchange.ajax.Login.doJSONAuth(Login.java: 738)
    at com.openexchange.ajax.Login.doGet(Login.java:725)
    at com.openexchange.ajax.Login.doPost(Login.java:858)
    at javax.servlet.http.HttpServlet.service(HttpServlet .java:727)
    at com.openexchange.ajax.AJAXServlet.service(AJAXServ let.java:428)
    at javax.servlet.http.HttpServlet.service(HttpServlet .java:820)
    at com.openexchange.ajp13.coyote.AjpProcessor.process (AjpProcessor.java:822)
    at com.openexchange.ajp13.coyote.sockethandler.Coyote Task.call(CoyoteTask.java:184)
    at java.util.concurrent.FutureTask$Sync.innerRun(Futu reTask.java:303)
    at java.util.concurrent.FutureTask.run(FutureTask.jav a:138)
    at com.openexchange.threadpool.internal.CustomThreadP oolExecutor$Worker.runTask(CustomThreadPoolExecuto r.java:752)
    at com.openexchange.threadpool.internal.CustomThreadP oolExecutor$Worker.run(CustomThreadPoolExecutor.ja va:781)
    at java.lang.Thread.run(Thread.java:662)
    Caused by: CTX-0007 Category=4 Message=Updating database ... Try again later. exceptionID=2053721904-40
    at com.openexchange.groupware.contexts.impl.ContextSt orage.getContext(ContextStorage.java:116)
    at com.openexchange.login.internal.LoginPerformer.fin dContext(LoginPerformer.java:257)
    at com.openexchange.login.internal.LoginPerformer.doL ogin(LoginPerformer.java:184)



    After reading the release notes I found that some fields must be in the database. Analising the database that fields not have been created/updated.

    example: table del_dates not have the field organizerId

    I think that the update of the database fail.

    how can I fix that problem?

    Best Regards
    Last edited by sergiosilva; 02-03-2012 at 04:33 AM.

  2. #2
    Join Date
    Feb 2007
    Location
    Olpe
    Posts
    49

    Default

    Hi!

    Please investigate the log files and search for the following messages:

    Starting blocking updates on schema
    Starting background updates on schema

    Then the OX backend lists the tasks that are executed. When this updates are finished the following message will appear:

    Finished blocking updates on schema
    Finished background updates on schema

    Please attach here the part from the log file between those 2 messages. This helps investigating what happens with your installation.

  3. #3
    Join Date
    Feb 2012
    Posts
    8

    Default

    Hi!

    thanks for reply.

    I investigate the messages with the command grep but only find the Starting blocking updates on schema message

    No other messages appear.

    In the open-xchange.log files I found:

    Starting blocking updates on schema oxgroupdatabase_5
    Feb 3, 2012 8:05:53 AM com.openexchange.log.internal.LoggerTask call
    FINEST: Logged at: com.openexchange.pooling.ReentrantLockPool.get(Ree ntrantLockPool.java:361)
    Get time: 0, Created: false
    com.openexchange.database.schema=oxgroupdatabase_5
    Feb 3, 2012 8:05:53 AM com.openexchange.log.internal.LoggerTask call
    FINEST: Logged at: com.openexchange.pooling.ReentrantLockPool.back(Re entrantLockPool.java:222)
    Back time: 0
    com.openexchange.database.schema=oxgroupdatabase_5
    Feb 3, 2012 8:05:53 AM com.openexchange.log.internal.LoggerTask call
    SEVERE: Logged at: com.openexchange.groupware.update.internal.UpdateP rocess.run(UpdateProcess.java:93)
    UPD-0003 Category=3 Message=Update conflict detected. Another process is currently updating schema oxgroupdatabase_5. exceptionID=-1363891429-282
    UPD-0003 Category=3 Message=Update conflict detected. Another process is currently updating schema oxgroupdatabase_5. exceptionID=-1363891429-282
    at com.openexchange.groupware.update.internal.UpdateE xecutor.runUpdates(UpdateExecutor.java:131)
    at com.openexchange.groupware.update.internal.UpdateE xecutor.execute(UpdateExecutor.java:110)
    at com.openexchange.groupware.update.internal.UpdateP rocess.run(UpdateProcess.java:89)
    at java.util.concurrent.Executors$RunnableAdapter.cal l(Executors.java:441)
    at java.util.concurrent.FutureTask$Sync.innerRun(Futu reTask.java:303)
    at java.util.concurrent.FutureTask.run(FutureTask.jav a:138)
    at com.openexchange.threadpool.internal.CustomThreadP oolExecutor$ScheduledFutureTask.run(CustomThreadPo olExecutor.java:901)
    at com.openexchange.threadpool.internal.CustomThreadP oolExecutor$Worker.runTask(CustomThreadPoolExecuto r.java:752)
    at com.openexchange.threadpool.internal.CustomThreadP oolExecutor$Worker.run(CustomThreadPoolExecutor.ja va:781)
    at java.lang.Thread.run(Thread.java:662)
    Caused by: UPD-0003 Category=3 Message=Update conflict detected. Another process is currently updating schema oxgroupdatabase_5. exceptionID=-1363891429-281
    at com.openexchange.groupware.update.internal.SchemaE xceptionFactory.createException(SchemaExceptionFac tory.java:74)
    at com.openexchange.groupware.update.internal.SchemaE xceptionFactory.createException(SchemaExceptionFac tory.java:60)
    at com.openexchange.exceptions.Exceptions.create(Exce ptions.java:141)
    at com.openexchange.exceptions.Exceptions.create(Exce ptions.java:145)
    at com.openexchange.exceptions.Exceptions.create(Exce ptions.java:149)
    at com.openexchange.groupware.update.internal.SchemaE xceptionCodes.create(SchemaExceptionCodes.java:155 )
    at com.openexchange.groupware.update.internal.SchemaS toreImpl.insertLock(SchemaStoreImpl.java:234)
    at com.openexchange.groupware.update.internal.SchemaS toreImpl.lockSchemaDB(SchemaStoreImpl.java:206)
    at com.openexchange.groupware.update.internal.SchemaS toreImpl.lockSchema(SchemaStoreImpl.java:188)
    at com.openexchange.groupware.update.internal.UpdateE xecutor.lockSchema(UpdateExecutor.java:193)
    at com.openexchange.groupware.update.internal.UpdateE xecutor.runUpdates(UpdateExecutor.java:121)

    in the open-xchange-admin.log files I found:


    Starting blocking updates on schema oxgroupdatabase_5
    Feb 2, 2012 6:00:11 PM org.apache.jcs.auxiliary.lateral.socket.tcp.Latera lTCPSender send
    SEVERE: Detected problem with connection: java.net.SocketException: Broken pipe
    Feb 2, 2012 6:00:11 PM org.apache.jcs.auxiliary.lateral.LateralCache handleException
    SEVERE: Disabling lateral cache due to error Failed to remove CacheKey: context=3, keys=[oxgroupdatabase_5] from OXDBPoolCache
    java.net.SocketException: Broken pipe
    at java.net.SocketOutputStream.socketWrite0(Native Method)
    at java.net.SocketOutputStream.socketWrite(SocketOutp utStream.java:92)
    at java.net.SocketOutputStream.write(SocketOutputStre am.java:136)
    at java.io.ObjectOutputStream$BlockDataOutputStream.d rain(ObjectOutputStream.java:1847)
    at java.io.ObjectOutputStream$BlockDataOutputStream.s etBlockDataMode(ObjectOutputStream.java:1756)
    at java.io.ObjectOutputStream.writeNonProxyDesc(Objec tOutputStream.java:1257)
    at java.io.ObjectOutputStream.writeClassDesc(ObjectOu tputStream.java:1211)
    at java.io.ObjectOutputStream.writeOrdinaryObject(Obj ectOutputStream.java:1395)
    at java.io.ObjectOutputStream.writeObject0(ObjectOutp utStream.java:1158)
    at java.io.ObjectOutputStream.writeFatalException(Obj ectOutputStream.java:1547)
    at java.io.ObjectOutputStream.writeObject(ObjectOutpu tStream.java:333)
    at org.apache.jcs.auxiliary.lateral.socket.tcp.Latera lTCPSender.send(LateralTCPSender.java:223)
    at org.apache.jcs.auxiliary.lateral.socket.tcp.Latera lTCPService.remove(LateralTCPService.java:172)
    at org.apache.jcs.auxiliary.lateral.LateralCache.remo ve(LateralCache.java:168)
    at org.apache.jcs.engine.CacheAdaptor.handleRemove(Ca cheAdaptor.java:107)
    at org.apache.jcs.engine.CacheEventQueue$RemoveEvent. doRun(CacheEventQueue.java:736)
    at org.apache.jcs.engine.CacheEventQueue$AbstractCach eEvent.run(CacheEventQueue.java:607)
    at org.apache.jcs.engine.CacheEventQueue$QProcessor.r un(CacheEventQueue.java:575)
    Feb 2, 2012 6:00:11 PM org.apache.jcs.engine.CacheEventQueue$AbstractCach eEvent run
    WARNING: java.net.SocketException: Broken pipe
    Feb 2, 2012 6:00:12 PM org.apache.jcs.engine.CacheEventQueue$AbstractCach eEvent run
    WARNING: Interrupted while sleeping for retry on event RemoveEvent for CacheKey: context=3, keys=[oxgroupdatabase_5].


    in that case some problem happens with a connection.

    How fix?

    The log files is to large to upload.

    I can put that log files in a web page and after that you can download?

    Regards

  4. #4
    Join Date
    Feb 2007
    Location
    Olpe
    Posts
    49

    Default

    So the administration daemon started to run the update tasks. The problem with the broken pipe is not related at all to update tasks. It is a message from the caching system that does not affect update tasks.

    What are the next messages after the broken pipe problem in the administration daemon log?

  5. #5
    Join Date
    Feb 2012
    Posts
    8

    Default

    Forget the broken pipe. My mistake with the log date.

    At the moment, from the update to 6.20.1 REV 5 the error in open-xchange-admin.log is:

    SEVERE: Database is locked or is now beeing updated, please try again later
    com.openexchange.admin.rmi.exceptions.DatabaseUpda teException: Database is locked or is now beeing updated, please try again later
    at com.openexchange.admin.rmi.impl.OXCommonImpl.check ContextAndSchema(OXCommonImpl.java:150)
    at com.openexchange.admin.rmi.impl.OXGroup.list(OXGro up.java:877)
    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)

    in the open-xchange.log is:

    SEVERE: Logged at: com.openexchange.ajax.Login.loginOperation(Login.j ava:944)
    CTX-0007 Category=4 Message=Updating database ... Try again later. exceptionID=-1363891429-980
    com.openexchange.ajp13.remoteAddress=127.0.0.1
    com.openexchange.ajp13.remotePort=41646
    CTX-0007 Category=4 Message=Updating database ... Try again later. exceptionID=-1363891429-980
    at com.openexchange.login.internal.LoginPerformer.doL ogin(LoginPerformer.java:217)
    at com.openexchange.login.internal.LoginPerformer.doL ogin(LoginPerformer.java:128)
    at com.openexchange.ajax.Login$11.doLogin(Login.java: 904)
    at com.openexchange.ajax.Login.loginOperation(Login.j ava:919)
    at com.openexchange.ajax.Login.doLogin(Login.java:901 )
    at com.openexchange.ajax.Login$1.handleRequest(Login. java:184)
    at com.openexchange.ajax.Login.doJSONAuth(Login.java: 738)
    at com.openexchange.ajax.Login.doGet(Login.java:725)
    at com.openexchange.ajax.Login.doPost(Login.java:858)
    at javax.servlet.http.HttpServlet.service(HttpServlet .java:727)
    at com.openexchange.ajax.AJAXServlet.service(AJAXServ let.java:428)
    at javax.servlet.http.HttpServlet.service(HttpServlet .java:820)
    at com.openexchange.ajp13.coyote.AjpProcessor.process (AjpProcessor.java:822)
    at com.openexchange.ajp13.coyote.sockethandler.Coyote Task.call(CoyoteTask.java:184)
    at java.util.concurrent.FutureTask$Sync.innerRun(Futu reTask.java:303)
    at java.util.concurrent.FutureTask.run(FutureTask.jav a:138)
    at com.openexchange.threadpool.internal.CustomThreadP oolExecutor$Worker.runTask(CustomThreadPoolExecuto r.java:752)
    at com.openexchange.threadpool.internal.CustomThreadP oolExecutor$Worker.run(CustomThreadPoolExecutor.ja va:781)
    at java.lang.Thread.run(Thread.java:662)
    Caused by: CTX-0007 Category=4 Message=Updating database ... Try again later. exceptionID=-1363891429-979
    at com.openexchange.groupware.contexts.impl.ContextSt orage.getContext(ContextStorage.java:116)
    at com.openexchange.login.internal.LoginPerformer.fin dContext(LoginPerformer.java:257)
    at com.openexchange.login.internal.LoginPerformer.doL ogin(LoginPerformer.java:184)
    ... 18 more
    Feb 3, 2012 11:59:52 AM com.openexchange.log.internal.LoggerTask call
    FINE: Logged at: com.openexchange.push.udp.PushOutputQueue.run(Push OutputQueue.java:427)
    get push objects from queue: 0
    Feb 3, 2012 11:59:54 AM com.openexchange.log.internal.LoggerTask call
    FINE: Logged at: com.openexchange.ajp13.coyote.AjpProcessor.process (AjpProcessor.java:723)
    ajpprocessor.io.read-timeout
    java.net.SocketTimeoutException: Read timed out
    at java.net.SocketInputStream.socketRead0(Native Method)
    at java.net.SocketInputStream.read(SocketInputStream. java:129)
    at com.openexchange.ajp13.coyote.AjpProcessor.read(Aj pProcessor.java:1913)
    at com.openexchange.ajp13.coyote.AjpProcessor.readMes sage(AjpProcessor.java:1986)
    at com.openexchange.ajp13.coyote.AjpProcessor.process (AjpProcessor.java:672)
    at com.openexchange.ajp13.coyote.sockethandler.Coyote Task.call(CoyoteTask.java:184)
    at java.util.concurrent.FutureTask$Sync.innerRun(Futu reTask.java:303)
    at java.util.concurrent.FutureTask.run(FutureTask.jav a:138)
    at com.openexchange.threadpool.internal.CustomThreadP oolExecutor$Worker.runTask(CustomThreadPoolExecuto r.java:752)
    at com.openexchange.threadpool.internal.CustomThreadP oolExecutor$Worker.run(CustomThreadPoolExecutor.ja va:781)
    at java.lang.Thread.run(Thread.java:662)
    Feb 3, 2012 11:59:54 AM com.openexchange.log.internal.LoggerTask call
    FINE: Logged at: com.openexchange.ajp13.coyote.AjpProcessor.process (AjpProcessor.java:723)
    ajpprocessor.io.read-timeout
    java.net.SocketTimeoutException: Read timed out
    at java.net.SocketInputStream.socketRead0(Native Method)
    at java.net.SocketInputStream.read(SocketInputStream. java:129)
    at com.openexchange.ajp13.coyote.AjpProcessor.read(Aj pProcessor.java:1913)
    at com.openexchange.ajp13.coyote.AjpProcessor.readMes sage(AjpProcessor.java:1986)
    at com.openexchange.ajp13.coyote.AjpProcessor.process (AjpProcessor.java:672)
    at com.openexchange.ajp13.coyote.sockethandler.Coyote Task.call(CoyoteTask.java:184)
    at java.util.concurrent.FutureTask$Sync.innerRun(Futu reTask.java:303)
    at java.util.concurrent.FutureTask.run(FutureTask.jav a:138)
    at com.openexchange.threadpool.internal.CustomThreadP oolExecutor$Worker.runTask(CustomThreadPoolExecuto r.java:752)
    at com.openexchange.threadpool.internal.CustomThreadP oolExecutor$Worker.run(CustomThreadPoolExecutor.ja va:781)
    at java.lang.Thread.run(Thread.java:662)
    eb 3, 2012 11:59:57 AM com.openexchange.log.internal.LoggerTask call
    FINEST: Logged at: com.openexchange.pooling.ReentrantLockPool.run(Ree ntrantLockPool.java:515)
    Starting cleaner run.
    Feb 3, 2012 11:59:57 AM com.openexchange.log.internal.LoggerTask call
    FINEST: Logged at: com.openexchange.pooling.ReentrantLockPool.run(Ree ntrantLockPool.java:515)
    Starting cleaner run.
    Feb 3, 2012 11:59:57 AM com.openexchange.log.internal.LoggerTask call
    FINEST: Logged at: com.openexchange.pooling.ReentrantLockPool.run(Ree ntrantLockPool.java:593)
    Clean run ending. Time: 0
    Feb 3, 2012 11:59:57 AM com.openexchange.log.internal.LoggerTask call
    FINEST: Logged at: com.openexchange.database.internal.Pools.run(Pools .java:139)
    Starting cleaner run.
    Feb 3, 2012 11:59:57 AM com.openexchange.log.internal.LoggerTask call
    FINEST: Logged at: com.openexchange.database.internal.Pools.run(Pools .java:164)
    Cleaner run ending.


    The database have 1,7 MB. I think that the update must be fast.

    I try to see the process list in mysql with the command SHOW PROCESSLIST, with root mysql user, and not find any proccess with my openxchange user. I try to enter with the openxchange database user in the oxgroupdatabase_5. No problems. The connection data configdb.properties. Can the database connection be broken? I try to see the configuration and I think is all ok.

  6. #6
    Join Date
    Feb 2007
    Location
    Olpe
    Posts
    49

    Default

    Your database is locked now because the update process started once and it did not finish. So every OX process notices that in the database and blocks further operations. This is wanted.

    So, please just answer my question. What are the next messages in the administration daemon log file after the message:

    Starting blocking updates on schema oxgroupdatabase_5

    This message indicates the start of the update process. And there must be further messages why this update process failed, stopped or whatever. We have to investigate those messages to be able to give an advice, what needs to be investigated or how to proceed.

    Another possible solution is to restore a database dump before the update. Then you can try to start the update process again. And then watch the messages during the update process and wait until it is finished.

  7. #7
    Join Date
    Feb 2012
    Posts
    8

    Default

    The firts log of the type Starting blocking updates on schema oxgroupdatabase_5, after upgrade is:


    Starting blocking updates on schema oxgroupdatabase_5
    Feb 2, 2012 6:00:11 PM org.apache.jcs.auxiliary.lateral.socket.tcp.Latera lTCPSender send
    SEVERE: Detected problem with connection: java.net.SocketException: Broken pipe
    Feb 2, 2012 6:00:11 PM org.apache.jcs.auxiliary.lateral.LateralCache handleException
    SEVERE: Disabling lateral cache due to error Failed to remove CacheKey: context=3, keys=[oxgroupdatabase_5] from OXDBPoolCache
    java.net.SocketException: Broken pipe
    at java.net.SocketOutputStream.socketWrite0(Native Method)
    at java.net.SocketOutputStream.socketWrite(SocketOutp utStream.java:92)
    at java.net.SocketOutputStream.write(SocketOutputStre am.java:136)
    at java.io.ObjectOutputStream$BlockDataOutputStream.d rain(ObjectOutputStream.java:1847)
    at java.io.ObjectOutputStream$BlockDataOutputStream.s etBlockDataMode(ObjectOutputStream.java:1756)
    at java.io.ObjectOutputStream.writeNonProxyDesc(Objec tOutputStream.java:1257)
    at java.io.ObjectOutputStream.writeClassDesc(ObjectOu tputStream.java:1211)
    at java.io.ObjectOutputStream.writeOrdinaryObject(Obj ectOutputStream.java:1395)
    at java.io.ObjectOutputStream.writeObject0(ObjectOutp utStream.java:1158)
    at java.io.ObjectOutputStream.writeFatalException(Obj ectOutputStream.java:1547)
    at java.io.ObjectOutputStream.writeObject(ObjectOutpu tStream.java:333)
    at org.apache.jcs.auxiliary.lateral.socket.tcp.Latera lTCPSender.send(LateralTCPSender.java:223)
    at org.apache.jcs.auxiliary.lateral.socket.tcp.Latera lTCPService.remove(LateralTCPService.java:172)
    at org.apache.jcs.auxiliary.lateral.LateralCache.remo ve(LateralCache.java:168)
    at org.apache.jcs.engine.CacheAdaptor.handleRemove(Ca cheAdaptor.java:107)
    at org.apache.jcs.engine.CacheEventQueue$RemoveEvent. doRun(CacheEventQueue.java:736)
    at org.apache.jcs.engine.CacheEventQueue$AbstractCach eEvent.run(CacheEventQueue.java:607)
    at org.apache.jcs.engine.CacheEventQueue$QProcessor.r un(CacheEventQueue.java:575)
    Feb 2, 2012 6:00:11 PM org.apache.jcs.engine.CacheEventQueue$AbstractCach eEvent run
    WARNING: java.net.SocketException: Broken pipe
    Feb 2, 2012 6:00:12 PM org.apache.jcs.engine.CacheEventQueue$AbstractCach eEvent run
    WARNING: Interrupted while sleeping for retry on event RemoveEvent for CacheKey: context=3, keys=[oxgroupdatabase_5].
    Feb 2, 2012 6:00:12 PM com.openexchange.log.internal.LoggerTask call
    SEVERE: Logged at: com.openexchange.groupware.update.internal.UpdateP rocess.run(UpdateProcess.java:93)
    UPD-0003 Category=3 Message=Update conflict detected. Another process is currently updating schema oxgroupdatabase_5. exceptionID=1100519292-2
    UPD-0003 Category=3 Message=Update conflict detected. Another process is currently updating schema oxgroupdatabase_5. exceptionID=1100519292-2
    at com.openexchange.groupware.update.internal.UpdateE xecutor.runUpdates(UpdateExecutor.java:131)
    at com.openexchange.groupware.update.internal.UpdateE xecutor.execute(UpdateExecutor.java:110)
    at com.openexchange.groupware.update.internal.UpdateP rocess.run(UpdateProcess.java:89)
    at java.util.concurrent.Executors$RunnableAdapter.cal l(Executors.java:441)
    at java.util.concurrent.FutureTask$Sync.innerRun(Futu reTask.java:303)
    at java.util.concurrent.FutureTask.run(FutureTask.jav a:138)
    at com.openexchange.threadpool.internal.CustomThreadP oolExecutor$ScheduledFutureTask.run(CustomThreadPo olExecutor.java:901)
    at com.openexchange.threadpool.internal.CustomThreadP oolExecutor$Worker.runTask(CustomThreadPoolExecuto r.java:752)
    at com.openexchange.threadpool.internal.CustomThreadP oolExecutor$Worker.run(CustomThreadPoolExecutor.ja va:781)
    at java.lang.Thread.run(Thread.java:662)
    Caused by: UPD-0003 Category=3 Message=Update conflict detected. Another process is currently updating schema oxgroupdatabase_5. exceptionID=1100519292-1
    at com.openexchange.groupware.update.internal.SchemaE xceptionFactory.createException(SchemaExceptionFac tory.java:74)
    at com.openexchange.groupware.update.internal.SchemaE xceptionFactory.createException(SchemaExceptionFac tory.java:60)

    at com.openexchange.exceptions.Exceptions.create(Exce ptions.java:141)
    at com.openexchange.exceptions.Exceptions.create(Exce ptions.java:145)
    at com.openexchange.exceptions.Exceptions.create(Exce ptions.java:149)
    at com.openexchange.groupware.update.internal.SchemaE xceptionCodes.create(SchemaExceptionCodes.java:155 )
    at com.openexchange.groupware.update.internal.SchemaS toreImpl.insertLock(SchemaStoreImpl.java:234)
    at com.openexchange.groupware.update.internal.SchemaS toreImpl.lockSchemaDB(SchemaStoreImpl.java:206)
    at com.openexchange.groupware.update.internal.SchemaS toreImpl.lockSchema(SchemaStoreImpl.java:188)
    at com.openexchange.groupware.update.internal.UpdateE xecutor.lockSchema(UpdateExecutor.java:193)
    at com.openexchange.groupware.update.internal.UpdateE xecutor.runUpdates(UpdateExecutor.java:121)
    ... 9 more
    Feb 2, 2012 6:00:15 PM com.openexchange.admin.rmi.impl.OXCommonImpl checkContextAndSchema
    SEVERE: Database is locked or is now beeing updated, please try again later
    com.openexchange.admin.rmi.exceptions.DatabaseUpda teException: Database is locked or is now beeing updated, please try again later
    at com.openexchange.admin.rmi.impl.OXCommonImpl.check ContextAndSchema(OXCommonImpl.java:150)
    at com.openexchange.admin.rmi.impl.OXUser.list(OXUser .java:1045)
    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)
    Feb 2, 2012 6:00:15 PM com.openexchange.log.internal.LoggerTask call
    INFO: Logged at: com.openexchange.groupware.update.internal.UpdateE xecutor.runUpdates(UpdateExecutor.java:119)
    Starting blocking updates on schema oxgroupdatabase_5
    Feb 2, 2012 6:00:15 PM com.openexchange.log.internal.LoggerTask call
    SEVERE: Logged at: com.openexchange.groupware.update.internal.UpdateP rocess.run(UpdateProcess.java:93)
    UPD-0003 Category=3 Message=Update conflict detected. Another process is currently updating schema oxgroupdatabase_5. exceptionID=1100519292-4
    UPD-0003 Category=3 Message=Update conflict detected. Another process is currently updating schema oxgroupdatabase_5. exceptionID=1100519292-4


    If more logs is needed please advice.

    Regards

  8. #8
    Join Date
    Feb 2007
    Location
    Olpe
    Posts
    49

    Default

    Okay, I see:

    Starting blocking updates on schema oxgroupdatabase_5

    and later

    Update conflict detected. Another process is currently updating schema oxgroupdatabase_5.

    So this is not the first time OX tried to update the database. Please investigate the groupware log file for earlier messages starting with

    Starting blocking updates on schema oxgroupdatabase_5

  9. #9
    Join Date
    Feb 2012
    Posts
    8

    Default

    The first message in open-xchange.log is:

    INFO: Logged at: com.openexchange.groupware.update.internal.UpdateE xecutor.runUpdates(UpdateExecutor.java:119)
    Starting blocking updates on schema oxgroupdatabase_5
    Feb 2, 2012 3:36:23 PM com.openexchange.log.internal.LoggerTask call
    INFO: Logged at: com.openexchange.groupware.update.internal.UpdateE xecutor.runUpdates(UpdateExecutor.java:153)
    Starting update task FolderAddIndex2LastModified on schema oxgroupdatabase_5.
    Feb 2, 2012 3:36:23 PM com.openexchange.log.internal.LoggerTask call
    INFO: Logged at: com.openexchange.groupware.update.tasks.FolderAddI ndex2LastModified.createMyIndex(FolderAddIndex2Las tModified.java:114)
    Creating new index named "lastModifiedIndex" with columns (cid, changing_date, module) on table oxfolder_tree.
    com.openexchange.database.schema=oxgroupdatabase_5
    Feb 2, 2012 3:36:24 PM com.openexchange.log.internal.LoggerTask call
    INFO: Logged at: com.openexchange.groupware.update.tasks.FolderAddI ndex2LastModified.createMyIndex(FolderAddIndex2Las tModified.java:114)
    Creating new index named "lastModifiedIndex" with columns (cid, changing_date, module) on table del_oxfolder_tree.
    com.openexchange.database.schema=oxgroupdatabase_5
    Feb 2, 2012 3:36:24 PM com.openexchange.log.internal.LoggerTask call
    INFO: Logged at: com.openexchange.groupware.update.internal.UpdateE xecutor.runUpdates(UpdateExecutor.java:166)
    Update task FolderAddIndex2LastModified on schema oxgroupdatabase_5 done.
    Feb 2, 2012 3:36:24 PM com.openexchange.log.internal.LoggerTask call
    INFO: Logged at: com.openexchange.groupware.update.internal.UpdateE xecutor.runUpdates(UpdateExecutor.java:153)
    Starting update task ContactAddOutlookAddressFieldsTask on schema oxgroupdatabase_5.
    Feb 2, 2012 3:36:27 PM com.openexchange.log.internal.LoggerTask call
    INFO: Logged at: com.openexchange.sessiond.impl.SessiondServiceImpl .getSession(SessiondServiceImpl.java:158)
    Session not found. ID: 84790ce59800475b8c2e200db1ec8b27
    Feb 2, 2012 3:36:27 PM com.openexchange.log.internal.LoggerTask call
    INFO: Logged at: com.openexchange.ajax.SessionServlet.getSession(Se ssionServlet.java:506)
    There is no session associated with session identifier: 84790ce59800475b8c2e200db1ec8b27
    Feb 2, 2012 3:36:27 PM com.openexchange.log.internal.LoggerTask call
    INFO: Logged at: com.openexchange.authentication.ldap.LDAPAuthentic ation.handleLoginInfo(LDAPAuthentication.java:136)
    User support successful authenticated.
    Feb 2, 2012 3:36:27 PM com.openexchange.log.internal.LoggerTask call
    INFO: Logged at: com.openexchange.groupware.contexts.impl.ContextSt orage.getContext(ContextStorage.java:117)
    CTX-0007 Category=4 Message=Updating database ... Try again later. exceptionID=-1187197703-25
    Feb 2, 2012 3:36:27 PM com.openexchange.log.internal.LoggerTask call
    INFO: Logged at: com.openexchange.ajax.Login$11.doLogin(Login.java: 904)


    but before that I find some errors. To fix that error(when try to login give a Internal Error), after upgrade, I do chown -R open-xchange.open-xchange /opt/open-xchange.

    The error are:

    SEVERE: Logged at: com.openexchange.database.internal.Management.regi sterPool(Management.java:107)
    JMX-0001 Category=10 Message=MBean registration denied: ManagementAgent is not running. exceptionID=-1187197703-20
    com.openexchange.ajp13.remoteAddress=127.0.0.1
    com.openexchange.ajp13.remotePort=37271
    com.openexchange.database.schema=oxgroupdatabase_5
    JMX-0001 Category=10 Message=MBean registration denied: ManagementAgent is not running. exceptionID=-1187197703-20
    at com.openexchange.management.internal.ManagementAge ntImpl.registerMBean(ManagementAgentImpl.java:186)
    at com.openexchange.database.internal.Management.regi sterPool(Management.java:100)
    at com.openexchange.database.internal.Management.addP ool(Management.java:184)
    at com.openexchange.database.internal.ContextDatabase LifeCycle.create(ContextDatabaseLifeCycle.java:109 )
    at com.openexchange.database.internal.Pools.getPool(P ools.java:108)
    at com.openexchange.database.internal.ReplicationMoni tor$1.get(ReplicationMonitor.java:98)
    at com.openexchange.database.internal.ReplicationMoni tor.checkActualAndFallback(ReplicationMonitor.java :151)
    at com.openexchange.database.internal.ReplicationMoni tor.checkActualAndFallback(ReplicationMonitor.java :141)
    at com.openexchange.database.internal.DatabaseService Impl.get(DatabaseServiceImpl.java:97)
    at com.openexchange.database.internal.DatabaseService Impl.getReadOnly(DatabaseServiceImpl.java:150)
    at com.openexchange.databaseold.Database.get(Database .java:114)
    at com.openexchange.server.impl.DBPool.pickup(DBPool. java:68)
    at com.openexchange.groupware.contexts.impl.RdbContex tStorage.getAdmin(RdbContextStorage.java:135)
    at com.openexchange.groupware.contexts.impl.RdbContex tStorage.loadContext(RdbContextStorage.java:197)
    at com.openexchange.groupware.contexts.impl.CachingCo ntextStorage$1.load(CachingContextStorage.java:129 )
    at com.openexchange.groupware.contexts.impl.CachingCo ntextStorage$1.load(CachingContextStorage.java:124 )
    at com.openexchange.caching.dynamic.Refresher.refresh (Refresher.java:205)
    at com.openexchange.caching.dynamic.Refresher.refresh (Refresher.java:146)
    at com.openexchange.caching.dynamic.Refresher.refresh (Refresher.java:118)
    at com.openexchange.groupware.contexts.impl.ContextRe loader.<init>(ContextReloader.java:75)
    at com.openexchange.groupware.contexts.impl.CachingCo ntextStorage.loadContext(CachingContextStorage.jav a:155)
    at com.openexchange.groupware.contexts.impl.ContextSt orage.getContext(ContextStorage.java:114)
    at com.openexchange.login.internal.LoginPerformer.fin dContext(LoginPerformer.java:257)
    at com.openexchange.login.internal.LoginPerformer.doL ogin(LoginPerformer.java:184)
    at com.openexchange.login.internal.LoginPerformer.doL ogin(LoginPerformer.java:128)
    at com.openexchange.ajax.Login$11.doLogin(Login.java: 904)
    at com.openexchange.ajax.Login.loginOperation(Login.j ava:919)
    at com.openexchange.ajax.Login.doLogin(Login.java:901 )
    at com.openexchange.ajax.Login$1.handleRequest(Login. java:184)
    at com.openexchange.ajax.Login.doJSONAuth(Login.java: 738)
    at com.openexchange.ajax.Login.doGet(Login.java:725)
    at com.openexchange.ajax.Login.doPost(Login.java:858)
    at javax.servlet.http.HttpServlet.service(HttpServlet .java:727)
    at com.openexchange.ajax.AJAXServlet.service(AJAXServ let.java:428)
    at javax.servlet.http.HttpServlet.service(HttpServlet .java:820)
    at com.openexchange.ajp13.coyote.AjpProcessor.process (AjpProcessor.java:822)
    at com.openexchange.ajp13.coyote.sockethandler.Coyote Task.call(CoyoteTask.java:184)
    at java.util.concurrent.FutureTask$Sync.innerRun(Futu reTask.java:303)
    at java.util.concurrent.FutureTask$Sync.innerRun(Futu reTask.java:303)
    at java.util.concurrent.FutureTask.run(FutureTask.jav a:138)
    at com.openexchange.threadpool.internal.CustomThreadP oolExecutor$Worker.runTask(CustomThreadPoolExecuto r.java:752)
    at com.openexchange.threadpool.internal.CustomThreadP oolExecutor$Worker.run(CustomThreadPoolExecutor.ja va:781)
    at java.lang.Thread.run(Thread.java:662)


    Best Regards

  10. #10
    Join Date
    Feb 2007
    Location
    Olpe
    Posts
    49

    Default

    The update process consinsts of this messages:

    Starting blocking updates on schema oxgroupdatabase_5
    Starting update task FolderAddIndex2LastModified on schema oxgroupdatabase_5.
    Update task FolderAddIndex2LastModified on schema oxgroupdatabase_5 done.
    Starting update task ContactAddOutlookAddressFieldsTask on schema oxgroupdatabase_5.

    All those messages are written by

    com.openexchange.groupware.update.internal.UpdateE xecutor.runUpdates

    There must be more of those messages. We need all of them to investigate what happened.

Bookmarks

Posting Permissions

  • You may not post new threads
  • You may not post replies
  • You may not post attachments
  • You may not edit your posts
  •