Thomas, I was able to checkout/compile and run a successful overnight test with the fixes that you added for the IllegalStateException. Note: I have not been able to keep it running overnight since I went back to MVStore and Version 183 - so this is good.
I am still concerned about the problem that I had with the commit() throwing an exception while actually commiting the changes and then not allowing the rollback.... ( from the previous reply to this discussion ) Any thoughts on that yet? On Monday, January 5, 2015 2:22:26 PM UTC-5, Kenton Garner wrote: > > Thomas, > > 1.) Thanks I will see if I can check that version out. > > 2.) I had a another problem recently that did throw an > IllegalStateException. I will attach the stack here, to see if it is the > same issue. > > 3.) This second exception that i am referring to highlighted another more > serious issue for me having to do with transaction / commit / rollback. > I have my connection set to AutoCommit=FALSE. > In this test I log the result of getAutoCommit() from the connection > returned from my connection pool to insure that it is still set to FALSE. > I perform one update and one select before committing the > transaction ( conn.commit(); ) > The IllegalStateException was thrown on the commit() call. > > Because I got an exception thrown I attempt to rollback() the > connection and then retry the commands. > > The problem is that the database committed the UPDATE even though > the commit() call failed and I attempted a rollback(). > > Subsequently, I Updated the row twice - which in this case causes > unrecoverable problems because the update decrements a counter one too many > times. > > So my question here would be why was the transaction committed when > an exception was thrown and what could I possibly do to prevent this? > > Exception stack... > > 15 Jan 02 18:21:36:599 WARN directory-poller-0 - [DataSync+ Send-03]: >> FwdMsgQueueManager: KENT runQuery - autocommit: [false] >> 15 Jan 02 18:21:36:894 WARN directory-poller-0 - [DataSync+ Send-03]: >> FwdMsgQueueManager: KENT DB closeForwardMessage(a): [UPDATE CDS_FWD_DATA >> SET PUBLISH_COUNT=(PUBLISH_COUNT-1) WHERE >> FILENAME='00-20150102182059026000_55430-ORACLE.GDS2DB_OWNER.0' AND >> DATE='2015-01-02 18:20:39.723501'] rows updated:[1] >> 15 Jan 02 18:21:36:894 WARN directory-poller-0 - [DataSync+ Send-03]: >> FwdMsgQueueManager: KENT DB closeForwardMessage(b): [SELECT PUBLISH_COUNT >> FROM CDS_FWD_DATA WHERE >> FILENAME='00-20150102182059026000_55430-ORACLE.GDS2DB_OWNER.0' AND >> DATE='2015-01-02 18:20:39.723501'] >> 15 Jan 02 18:21:36:894 WARN directory-poller-0 - [DataSync+ Send-03]: >> FwdMsgQueueManager: KENT DB closeForwardMessage(b1): counter: [1] -- is it >> <= 0 >> 15 Jan 02 18:21:37:769 ERROR directory-poller-0 - [DataSync+ Send-03]: >> h2database: jdbc exception >> org.h2.jdbc.JdbcSQLException: General error: >> "java.lang.IllegalStateException: File corrupted in chunk 373738, expected >> page length =< 12288, got 1677721856 [1.4.184/6]"; SQL statement: >> COMMIT [50000-184] >> at >> org.h2.message.DbException.getJdbcSQLException(DbException.java:345) >> at org.h2.message.DbException.get(DbException.java:168) >> at org.h2.message.DbException.convert(DbException.java:295) >> at org.h2.command.Command.executeUpdate(Command.java:262) >> at org.h2.server.TcpServerThread.process(TcpServerThread.java:345) >> at org.h2.server.TcpServerThread.run(TcpServerThread.java:159) >> at java.lang.Thread.run(Thread.java:745) >> Caused by: java.lang.IllegalStateException: File corrupted in chunk >> 373738, expected page length =< 12288, got 1677721856 [1.4.184/6] >> at >> org.h2.mvstore.DataUtils.newIllegalStateException(DataUtils.java:773) >> at org.h2.mvstore.Page$PageChildren.read(Page.java:1035) >> at org.h2.mvstore.MVStore.readPageChunkReferences(MVStore.java:1272) >> at org.h2.mvstore.MVStore.collectReferencedChunks(MVStore.java:1245) >> at org.h2.mvstore.MVStore.collectReferencedChunks(MVStore.java:1249) >> at org.h2.mvstore.MVStore.collectReferencedChunks(MVStore.java:1232) >> at org.h2.mvstore.MVStore.freeUnusedChunks(MVStore.java:1191) >> at org.h2.mvstore.MVStore.compactRewrite(MVStore.java:1793) >> at org.h2.mvstore.MVStore.compact(MVStore.java:1678) >> at org.h2.mvstore.db.MVTableEngine$Store.flush(MVTableEngine.java:198) >> at org.h2.engine.Database.flush(Database.java:2019) >> at org.h2.engine.Session.endTransaction(Session.java:549) >> at org.h2.engine.Session.commit(Session.java:536) >> at >> org.h2.command.dml.TransactionCommand.update(TransactionCommand.java:46) >> at org.h2.command.CommandContainer.update(CommandContainer.java:78) >> at org.h2.command.Command.executeUpdate(Command.java:254) >> ... 3 more >> >> at org.h2.engine.SessionRemote.done(SessionRemote.java:622) >> at org.h2.command.CommandRemote.executeUpdate(CommandRemote.java:191) >> at org.h2.jdbc.JdbcConnection.commit(JdbcConnection.java:468) >> at >> com.zaxxer.hikari.proxy.ConnectionProxy.commit(ConnectionProxy.java:298) >> at >> com.zaxxer.hikari.proxy.ConnectionJavassistProxy.commit(ConnectionJavassistProxy.java) >> at >> com.issinc.cds.sb.mfwd.impl.FwdMsgQueueManager.runQuery(FwdMsgQueueManager.java:867) >> at >> com.issinc.cds.sb.mfwd.impl.FwdMsgQueueManager.closeForwardedMessage(FwdMsgQueueManager.java:332) >> at >> com.issinc.cds.sbsend.input.poller.MessageForwardDirPollerRenamer$1$1.requestHandled(MessageForwardDirPollerRenamer.java:125) >> at >> com.issinc.cds.sbsend.mfwd.proc.MessageForwardMessageRouter.processRequest(MessageForwardMessageRouter.java:106) >> at >> com.issinc.cds.sbsend.input.poller.MessageForwardDirPollerRenamer$1.ProcessData(MessageForwardDirPollerRenamer.java:111) >> at >> com.issinc.cds.sb.mfwd.impl.FwdMsgQueueManager$4.runTask(FwdMsgQueueManager.java:496) >> at >> com.issinc.cds.sb.mfwd.impl.FwdMsgQueueManager.runQuery(FwdMsgQueueManager.java:866) >> at >> com.issinc.cds.sb.mfwd.impl.FwdMsgQueueManager.getMessage(FwdMsgQueueManager.java:461) >> at >> com.issinc.cds.sbsend.input.poller.MessageForwardDirPollerRenamer.rename(MessageForwardDirPollerRenamer.java:104) >> at >> org.sadun.util.polling.DirectoryPoller.runCycle(DirectoryPoller.java:1171) >> at >> org.sadun.util.polling.DirectoryPoller.run(DirectoryPoller.java:964) >> at >> com.issinc.cds.poller.CDSDirectoryPoller.run(CDSDirectoryPoller.java:85) >> >> 15 Jan 02 18:21:37:770 WARN directory-poller-0 - [DataSync+ Send-03]: >> FwdMsgQueueManager: KENT runQuery - rollback ! >> >> 15 Jan 02 18:21:37:784 ERROR directory-poller-0 - [DataSync+ Send-03]: >> h2database: jdbc exception >> org.h2.jdbc.JdbcSQLException: General error: >> "java.lang.IllegalStateException: File corrupted in chunk 373738, expected >> page length =< 12288, got 1677721856 [1.4.184/6]"; SQL statement: >> ROLLBACK [50000-184] >> at >> org.h2.message.DbException.getJdbcSQLException(DbException.java:345) >> at org.h2.message.DbException.get(DbException.java:168) >> at org.h2.message.DbException.convert(DbException.java:295) >> at org.h2.engine.Database.flush(Database.java:2021) >> at org.h2.engine.Session.endTransaction(Session.java:549) >> at org.h2.engine.Session.commit(Session.java:536) >> at >> org.h2.command.dml.TransactionCommand.update(TransactionCommand.java:46) >> at org.h2.command.CommandContainer.update(CommandContainer.java:78) >> at org.h2.command.Command.executeUpdate(Command.java:254) >> at org.h2.server.TcpServerThread.process(TcpServerThread.java:345) >> at org.h2.server.TcpServerThread.run(TcpServerThread.java:159) >> at java.lang.Thread.run(Thread.java:745) >> Caused by: java.lang.IllegalStateException: File corrupted in chunk >> 373738, expected page length =< 12288, got 1677721856 [1.4.184/6] >> at >> org.h2.mvstore.DataUtils.newIllegalStateException(DataUtils.java:773) >> at org.h2.mvstore.Page$PageChildren.read(Page.java:1035) >> at org.h2.mvstore.MVStore.readPageChunkReferences(MVStore.java:1272) >> at org.h2.mvstore.MVStore.collectReferencedChunks(MVStore.java:1245) >> at org.h2.mvstore.MVStore.collectReferencedChunks(MVStore.java:1249) >> at org.h2.mvstore.MVStore.collectReferencedChunks(MVStore.java:1232) >> at org.h2.mvstore.MVStore.freeUnusedChunks(MVStore.java:1191) >> at org.h2.mvstore.MVStore.compactRewrite(MVStore.java:1793) >> at org.h2.mvstore.MVStore.compact(MVStore.java:1678) >> at org.h2.mvstore.db.MVTableEngine$Store.flush(MVTableEngine.java:198) >> at org.h2.engine.Database.flush(Database.java:2019) >> ... 8 more >> >> at org.h2.engine.SessionRemote.done(SessionRemote.java:622) >> at org.h2.command.CommandRemote.executeUpdate(CommandRemote.java:191) >> at >> org.h2.jdbc.JdbcConnection.rollbackInternal(JdbcConnection.java:1501) >> at org.h2.jdbc.JdbcConnection.rollback(JdbcConnection.java:489) >> at >> com.zaxxer.hikari.proxy.ConnectionProxy.rollback(ConnectionProxy.java:306) >> at >> com.zaxxer.hikari.proxy.ConnectionJavassistProxy.rollback(ConnectionJavassistProxy.java) >> at com.issinc.cds.db.DBUtils.rollbackQuietly(DBUtils.java:142) >> at >> com.issinc.cds.sb.mfwd.impl.FwdMsgQueueManager.runQuery(FwdMsgQueueManager.java:876) >> at >> com.issinc.cds.sb.mfwd.impl.FwdMsgQueueManager.closeForwardedMessage(FwdMsgQueueManager.java:332) >> at >> com.issinc.cds.sbsend.input.poller.MessageForwardDirPollerRenamer$1$1.requestHandled(MessageForwardDirPollerRenamer.java:125) >> at >> com.issinc.cds.sbsend.mfwd.proc.MessageForwardMessageRouter.processRequest(MessageForwardMessageRouter.java:106) >> at >> com.issinc.cds.sbsend.input.poller.MessageForwardDirPollerRenamer$1.ProcessData(MessageForwardDirPollerRenamer.java:111) >> at >> com.issinc.cds.sb.mfwd.impl.FwdMsgQueueManager$4.runTask(FwdMsgQueueManager.java:496) >> at >> com.issinc.cds.sb.mfwd.impl.FwdMsgQueueManager.runQuery(FwdMsgQueueManager.java:866) >> at >> com.issinc.cds.sb.mfwd.impl.FwdMsgQueueManager.getMessage(FwdMsgQueueManager.java:461) >> at >> com.issinc.cds.sbsend.input.poller.MessageForwardDirPollerRenamer.rename(MessageForwardDirPollerRenamer.java:104) >> at >> org.sadun.util.polling.DirectoryPoller.runCycle(DirectoryPoller.java:1171) >> at >> org.sadun.util.polling.DirectoryPoller.run(DirectoryPoller.java:964) >> at >> com.issinc.cds.poller.CDSDirectoryPoller.run(CDSDirectoryPoller.java:85) >> >> > > > > > > > > > > > > > > > > > > On Monday, January 5, 2015 12:15:57 PM UTC-5, Thomas Mueller wrote: >> >> Hi, >> >> I think I found and fixed this problem now. The fix is in the trunk. >> Usually, the exception message is different (IllegalStateException instead >> of ArrayIndexOutOfBoundsException), but from the stack trace it's >> relatively clear it is the same issue. >> >> Regards, >> Thomas >> >> >> On Saturday, December 27, 2014, Kenton Garner <kenton.gar...@gmail.com> >> wrote: >> >>> I am not sure what caused this. I have the corrupt database. Do you >>> have any ideas? >>> >>> I will include the stack traces from each of the three application that >>> were accessing the database at the time... >>> >>> #1 >>> ------------------------------------------------------------- >>> 14 Dec 23 00:01:31:559 ERROR MVStore background writer >>> nio:/opt/cds/dataSyncPlus/data/audit/msgfwd.mv.db - [DataSync+ Recv-00]: >>> h2database: msgfwd:database flush >>> org.h2.message.DbException: General error: >>> "java.lang.ArrayIndexOutOfBoundsException" [50000-184] >>> at org.h2.message.DbException.get(DbException.java:168) >>> at org.h2.message.DbException.convert(DbException.java:295) >>> at >>> org.h2.mvstore.db.MVTableEngine$1.uncaughtException(MVTableEngine.java:93) >>> at org.h2.mvstore.MVStore.writeInBackground(MVStore.java:2374) >>> at >>> org.h2.mvstore.MVStore$BackgroundWriterThread.run(MVStore.java:2560) >>> Caused by: org.h2.jdbc.JdbcSQLException: General error: >>> "java.lang.ArrayIndexOutOfBoundsException" [50000-184] >>> at >>> org.h2.message.DbException.getJdbcSQLException(DbException.java:345) >>> ... 5 more >>> Caused by: java.lang.ArrayIndexOutOfBoundsException >>> ------------------------------------------------------------- >>> >>> #2 >>> ------------------------------------------------------------- >>> 14 Dec 23 00:01:30:453 ERROR directory-poller-0 - [DataSync+ Send-02]: >>> h2database: jdbc exception >>> org.h2.jdbc.JdbcSQLException: General error: >>> "java.lang.ArrayIndexOutOfBoundsException" [50000-184] >>> at >>> org.h2.message.DbException.getJdbcSQLException(DbException.java:345) >>> at org.h2.message.DbException.get(DbException.java:168) >>> at org.h2.message.DbException.convert(DbException.java:295) >>> at org.h2.server.TcpServerThread.sendError(TcpServerThread.java:221) >>> at org.h2.server.TcpServerThread.run(TcpServerThread.java:161) >>> at java.lang.Thread.run(Thread.java:745) >>> Caused by: java.lang.ArrayIndexOutOfBoundsException >>> >>> at org.h2.engine.SessionRemote.done(SessionRemote.java:622) >>> at org.h2.command.CommandRemote.executeUpdate(CommandRemote.java:191) >>> at >>> org.h2.jdbc.JdbcStatement.executeUpdateInternal(JdbcStatement.java:130) >>> at org.h2.jdbc.JdbcStatement.executeUpdate(JdbcStatement.java:115) >>> at >>> com.zaxxer.hikari.proxy.StatementJavassistProxy.executeUpdate(StatementJavassistProxy.java) >>> at >>> com.issinc.cds.sb.mfwd.impl.FwdMsgQueueManager$3.runTask(FwdMsgQueueManager.java:332) >>> at >>> com.issinc.cds.sb.mfwd.impl.FwdMsgQueueManager.runQuery(FwdMsgQueueManager.java:818) >>> at >>> com.issinc.cds.sb.mfwd.impl.FwdMsgQueueManager.closeForwardedMessage(FwdMsgQueueManager.java:296) >>> at >>> com.issinc.cds.sbsend.input.poller.MessageForwardDirPollerRenamer$1$1.requestHandled(MessageForwardDirPollerRenamer.java:121) >>> at >>> com.issinc.cds.sbsend.mfwd.proc.MessageForwardMessageRouter.processRequest(MessageForwardMessageRouter.java:106) >>> at >>> com.issinc.cds.sbsend.input.poller.MessageForwardDirPollerRenamer$1.ProcessData(MessageForwardDirPollerRenamer.java:111) >>> at >>> com.issinc.cds.sb.mfwd.impl.FwdMsgQueueManager$4.runTask(FwdMsgQueueManager.java:451) >>> at >>> com.issinc.cds.sb.mfwd.impl.FwdMsgQueueManager.runQuery(FwdMsgQueueManager.java:818) >>> at >>> com.issinc.cds.sb.mfwd.impl.FwdMsgQueueManager.getMessage(FwdMsgQueueManager.java:419) >>> at >>> com.issinc.cds.sbsend.input.poller.MessageForwardDirPollerRenamer.rename(MessageForwardDirPollerRenamer.java:104) >>> at >>> org.sadun.util.polling.DirectoryPoller.runCycle(DirectoryPoller.java:1171) >>> at >>> org.sadun.util.polling.DirectoryPoller.run(DirectoryPoller.java:964) >>> at >>> com.issinc.cds.poller.CDSDirectoryPoller.run(CDSDirectoryPoller.java:85) >>> >>> 14 Dec 23 00:01:30:458 ERROR directory-poller-0 - [DataSync+ Send-02]: >>> FwdMsgQueueManager: SQLException on [DELETE FROM CDS_FWD_DATA WHERE >>> FILENAME='00-20141222235557170000_58636-ORACLE.GDS2DB_OWNER.0' AND >>> DATE='2014-12-22 23:55:42.531850'] >>> state:[HY000] code:[50000] >>> >>> ------------------------------------------------------------- >>> >>> >>> >>> #3 >>> ------------------------------------------------------------- >>> 14 Dec 23 00:01:30:181 ERROR directory-poller-0 - [DataSync+ Send-03]: >>> FwdMsgQueueManager: SQLException on [UPDATE CDS_FWD_DATA SET >>> PUBLISH_COUNT=(PUBLISH_COUNT-1) WHERE >>> FILENAME='00-20141223000113236000_60456-ORACLE.GDS2DB_OWNER.0' AND >>> DATE='2014-12-23 00:00:58.638670'] >>> state:[HY000] code:[50000] >>> >>> org.h2.jdbc.JdbcSQLException: General error: >>> "java.lang.ArrayIndexOutOfBoundsException: -2635" [50000-184] >>> at >>> org.h2.message.DbException.getJdbcSQLException(DbException.java:345) >>> at org.h2.message.DbException.get(DbException.java:168) >>> at org.h2.message.DbException.convert(DbException.java:295) >>> at org.h2.server.TcpServerThread.sendError(TcpServerThread.java:221) >>> at org.h2.server.TcpServerThread.run(TcpServerThread.java:161) >>> at java.lang.Thread.run(Thread.java:745) >>> Caused by: java.lang.ArrayIndexOutOfBoundsException: -2635 >>> at org.h2.compress.CompressLZF.expand(CompressLZF.java:413) >>> at org.h2.mvstore.Page.read(Page.java:696) >>> at org.h2.mvstore.Page.read(Page.java:194) >>> at org.h2.mvstore.MVStore.readPage(MVStore.java:1820) >>> at org.h2.mvstore.MVMap.readPage(MVMap.java:736) >>> at org.h2.mvstore.Page.getChildPage(Page.java:216) >>> at org.h2.mvstore.MVMap.rewrite(MVMap.java:823) >>> at org.h2.mvstore.MVMap.rewrite(MVMap.java:823) >>> at org.h2.mvstore.MVMap.rewrite(MVMap.java:782) >>> at org.h2.mvstore.MVStore.compactRewrite(MVStore.java:1786) >>> at org.h2.mvstore.MVStore.compact(MVStore.java:1678) >>> at >>> org.h2.mvstore.db.MVTableEngine$Store.flush(MVTableEngine.java:198) >>> at org.h2.engine.Database.flush(Database.java:2019) >>> at org.h2.engine.Session.endTransaction(Session.java:549) >>> at org.h2.engine.Session.commit(Session.java:536) >>> at org.h2.command.Command.stop(Command.java:152) >>> at org.h2.command.Command.executeUpdate(Command.java:284) >>> at org.h2.server.TcpServerThread.process(TcpServerThread.java:345) >>> at org.h2.server.TcpServerThread.run(TcpServerThread.java:159) >>> ... 1 more >>> >>> at org.h2.engine.SessionRemote.done(SessionRemote.java:622) >>> at org.h2.command.CommandRemote.executeUpdate(CommandRemote.java:191) >>> at >>> org.h2.jdbc.JdbcStatement.executeUpdateInternal(JdbcStatement.java:130) >>> at org.h2.jdbc.JdbcStatement.executeUpdate(JdbcStatement.java:115) >>> at >>> com.zaxxer.hikari.proxy.StatementJavassistProxy.executeUpdate(StatementJavassistProxy.java) >>> at >>> com.issinc.cds.sb.mfwd.impl.FwdMsgQueueManager$3.runTask(FwdMsgQueueManager.java:312) >>> at >>> com.issinc.cds.sb.mfwd.impl.FwdMsgQueueManager.runQuery(FwdMsgQueueManager.java:818) >>> at >>> com.issinc.cds.sb.mfwd.impl.FwdMsgQueueManager.closeForwardedMessage(FwdMsgQueueManager.java:296) >>> at >>> com.issinc.cds.sbsend.input.poller.MessageForwardDirPollerRenamer$1$1.requestHandled(MessageForwardDirPollerRenamer.java:121) >>> at >>> com.issinc.cds.sbsend.mfwd.proc.MessageForwardMessageRouter.processRequest(MessageForwardMessageRouter.java:106) >>> at >>> com.issinc.cds.sbsend.input.poller.MessageForwardDirPollerRenamer$1.ProcessData(MessageForwardDirPollerRenamer.java:111) >>> at >>> com.issinc.cds.sb.mfwd.impl.FwdMsgQueueManager$4.runTask(FwdMsgQueueManager.java:451) >>> at >>> com.issinc.cds.sb.mfwd.impl.FwdMsgQueueManager.runQuery(FwdMsgQueueManager.java:818) >>> at >>> com.issinc.cds.sb.mfwd.impl.FwdMsgQueueManager.getMessage(FwdMsgQueueManager.java:419) >>> at >>> com.issinc.cds.sbsend.input.poller.MessageForwardDirPollerRenamer.rename(MessageForwardDirPollerRenamer.java:104) >>> at >>> org.sadun.util.polling.DirectoryPoller.runCycle(DirectoryPoller.java:1171) >>> at >>> org.sadun.util.polling.DirectoryPoller.run(DirectoryPoller.java:964) >>> at >>> com.issinc.cds.poller.CDSDirectoryPoller.run(CDSDirectoryPoller.java:85) >>> ------------------------------------------------------------- >>> >>> >>> -- >>> You received this message because you are subscribed to the Google >>> Groups "H2 Database" group. >>> To unsubscribe from this group and stop receiving emails from it, send >>> an email to h2-database+unsubscr...@googlegroups.com. >>> To post to this group, send email to h2-database@googlegroups.com. >>> Visit this group at http://groups.google.com/group/h2-database. >>> For more options, visit https://groups.google.com/d/optout. >>> >> -- You received this message because you are subscribed to the Google Groups "H2 Database" group. To unsubscribe from this group and stop receiving emails from it, send an email to h2-database+unsubscr...@googlegroups.com. To post to this group, send email to h2-database@googlegroups.com. Visit this group at http://groups.google.com/group/h2-database. For more options, visit https://groups.google.com/d/optout.
