Critical error on QTI test submit

[glareff]

Hello,

I have created a qti test, and it seems that whenever I mark all answers and submit, I get this error. If I do not answer at least 1 question, then it submits without problems Any suggestions what may be wrong? Thanks!

<dispatchinfo>
<componentinfo>
<compname>olatmodiqrun</compname>
<compclass>org.olat.core.gui.components.velocity.VelocityContainer</compclass>
<extendedinfo>page:org/olat/modules/iq/_content/qti.html </extendedinfo>
<event>
<class>org.olat.core.gui.control.Event</class>
<command>sas</command>
<tostring>com:sas,org.olat.core.gui.control.Event@1bc04</tostring>
</event>
</componentinfo>
<controllerinfo>
<controllername>org.olat.modules.iq.IQDisplayController</controllername>
<controllername>org.olat.course.nodes.iq.IQRunController</controllername>
<controllername>org.olat.course.run.RunMainController</controllername>
<controllername>org.olat.core.commons.fullWebApp.BaseFullWebappController</controllername>
<controllername>org.olat.core.commons.chiefcontrollers.controller.simple.SimpleBaseController</controllername>
<controllername>org.olat.core.commons.chiefcontrollers.BaseChiefController</controllername>
</controllerinfo>
</dispatchinfo>
Throwable: org.olat.core.logging.KnownIssueException

logmsg:DBRuntimeException - Row was updated or deleted... (OLAT-3570)
message:DBRuntimeException - Row was updated or deleted... (OLAT-3570),org.olat.core.logging.KnownIssueException

at org.olat.course.nodes.IQTESTCourseNode.updateUserScoreEvaluation(IQTESTCourseNode.java:267)
at org.olat.course.nodes.iq.IQRunController.event(IQRunController.java:474)
at org.olat.core.gui.control.DefaultController$1.run(DefaultController.java:228)
at org.olat.core.logging.activity.ThreadLocalUserActivityLoggerInstaller.runWithUserActivityLogger(ThreadLocalUserActivityLoggerInstaller.java:108)
at org.olat.core.gui.control.DefaultController.dispatchEvent(DefaultController.java:225)
at org.olat.core.gui.control.DefaultController.fireEvent(DefaultController.java:173)
at org.olat.modules.iq.IQDisplayController.generateDetailsResults(IQDisplayController.java:555)
at org.olat.modules.iq.IQDisplayController.postSubmitAssessment(IQDisplayController.java:539)
at org.olat.modules.iq.IQDisplayController.event(IQDisplayController.java:496)
at org.olat.core.gui.control.DefaultController.dispatchEvent(DefaultController.java:184)

---

1. cause:

message:KnownIssueException,java.lang.Exception

at org.olat.core.logging.KnownIssueException.(KnownIssueException.java:58)
at org.olat.course.nodes.IQTESTCourseNode.updateUserScoreEvaluation(IQTESTCourseNode.java:267)
at org.olat.course.nodes.iq.IQRunController.event(IQRunController.java:474)
at org.olat.core.gui.control.DefaultController$1.run(DefaultController.java:228)
at org.olat.core.logging.activity.ThreadLocalUserActivityLoggerInstaller.runWithUserActivityLogger(ThreadLocalUserActivityLoggerInstaller.java:108)
at org.olat.core.gui.control.DefaultController.dispatchEvent(DefaultController.java:225)
at org.olat.core.gui.control.DefaultController.fireEvent(DefaultController.java:173)
at org.olat.modules.iq.IQDisplayController.generateDetailsResults(IQDisplayController.java:555)
at org.olat.modules.iq.IQDisplayController.postSubmitAssessment(IQDisplayController.java:539)

[glareff]

even more testing reveals that the error occurs when the pasing score is 10/20 or more then the error occurs. If passing score is 7/20 for example it seems to work fine.

[Stéphane Rossé]

Hi

Can you give some information on your setup? Databse you use, OS... And the more important which version of OpenOLAT you use? Perhaps can you export and attach the test so I can look at it on Monday.

Greetings

Stéphane

[Stéphane Rossé]

Hi

Can you send me the test? It's important because I cannot reproduce the issue with our own tests. I must know the exact version of your OpenOLAT instance too as we have made some bug fixes in the QTI environment the last few months.

Stéphane

Le vendredi 14 septembre 2012 18:50:33 UTC+2, glareff a écrit :

[glareff]

Dear Stéphane,

Did you get my message to your email last week? I sent you the test + specifications of our system.

Thanks and regards,
Ilya

[Stéphane Rossé]

No, I haven't see your mail, perhaps I haven't see it in spam filter. Can you send me the test a second time?

[Ilya Lazarev]

Stéphane,

I attached another dummy that I created that also fails on our linux deployment.

Thanks,
Ilya

--
Sie erhalten diese Nachricht, weil Sie Mitglied sind von Google
Groups-Gruppe "OpenOLAT".
Für das Erstellen von Beiträgen in dieser Gruppe senden Sie eine E-Mail
an open...@googlegroups.com
Um sich von dieser Gruppe abzumelden, senden Sie eine E-Mail an
openolat+u...@googlegroups.com
Weitere Optionen finden Sie in dieser Gruppe unter
http://groups.google.com/group/openolat?hl=de
-------------------------------------------------------------------------------------------------------------------
OpenOLAT - infinite learning - http://www.openolat.org

[glareff]

Here is the full stack trace:

14:27:40,348 ERROR JDBCExceptionReporter:101 - Data truncation: Out of range value adjusted for column 'score' at row 1
14:27:40,350 ERROR AbstractFlushingEventListener:324 - Could not synchronize database state with session
org.hibernate.exception.DataException: could not insert: [org.olat.ims.qti.QTIResultSet]
        at org.hibernate.exception.SQLStateConverter.convert(SQLStateConverter.java:100)
        at org.hibernate.exception.JDBCExceptionHelper.convert(JDBCExceptionHelper.java:66)
        at org.hibernate.persister.entity.AbstractEntityPersister.insert(AbstractEntityPersister.java:2285)
        at org.hibernate.persister.entity.AbstractEntityPersister.insert(AbstractEntityPersister.java:2678)
        at org.hibernate.action.EntityInsertAction.execute(EntityInsertAction.java:79)
        at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:279)
        at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:263)
        at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:167)
        at org.hibernate.event.def.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:321)
        at org.hibernate.event.def.DefaultAutoFlushEventListener.onAutoFlush(DefaultAutoFlushEventListener.java:64)
        at org.hibernate.impl.SessionImpl.autoFlushIfRequired(SessionImpl.java:997)
        at org.hibernate.impl.SessionImpl.list(SessionImpl.java:1142)
        at org.hibernate.impl.QueryImpl.list(QueryImpl.java:102)
        at org.olat.core.commons.persistence.DBQueryImpl.list(DBQueryImpl.java:125)
        at org.olat.course.assessment.NewCachePersistingAssessmentManager.loadPropertiesFor(NewCachePersistingAssessmentManager.java:177)
        at org.olat.course.assessment.NewCachePersistingAssessmentManager.getOrLoadScorePassedAttemptsMap(NewCachePersistingAssessmentManager.java:217)
        at org.olat.course.assessment.NewCachePersistingAssessmentManager.putPropertyIntoCache(NewCachePersistingAssessmentManager.java:256)
        at org.olat.course.assessment.NewCachePersistingAssessmentManager.saveNodeScore(NewCachePersistingAssessmentManager.java:345)
        at org.olat.course.assessment.NewCachePersistingAssessmentManager$5.execute(NewCachePersistingAssessmentManager.java:816)
        at org.olat.course.assessment.NewCachePersistingAssessmentManager$5.execute(NewCachePersistingAssessmentManager.java:811)
        at org.olat.commons.coordinate.cluster.ClusterSyncer.doInSync(ClusterSyncer.java:98)
        at org.olat.course.assessment.NewCachePersistingAssessmentManager.saveScoreEvaluation(NewCachePersistingAssessmentManager.java:811)
        at org.olat.course.nodes.IQTESTCourseNode.updateUserScoreEvaluation(IQTESTCourseNode.java:265)

        at org.olat.course.nodes.iq.IQRunController.event(IQRunController.java:474)
        at org.olat.core.gui.control.DefaultController$1.run(DefaultController.java:228)
        at org.olat.core.logging.activity.ThreadLocalUserActivityLoggerInstaller.runWithUserActivityLogger(ThreadLocalUserActivityLoggerInstaller.java:108)
        at org.olat.core.gui.control.DefaultController.dispatchEvent(DefaultController.java:225)
        at org.olat.core.gui.control.DefaultController.fireEvent(DefaultController.java:173)
        at org.olat.modules.iq.IQDisplayController.generateDetailsResults(IQDisplayController.java:555)
        at org.olat.modules.iq.IQDisplayController.postSubmitAssessment(IQDisplayController.java:539)
        at org.olat.modules.iq.IQDisplayController.event(IQDisplayController.java:496)
        at org.olat.core.gui.control.DefaultController.dispatchEvent(DefaultController.java:184)

        at org.olat.core.gui.components.Component$1.run(Component.java:229)
        at org.olat.core.logging.activity.ThreadLocalUserActivityLoggerInstaller.runWithUserActivityLogger(ThreadLocalUserActivityLoggerInstaller.java:108)
        at org.olat.core.gui.components.Component.fireEvent(Component.java:226)
        at org.olat.core.gui.components.velocity.VelocityContainer.doDispatchRequest(VelocityContainer.java:116)
        at org.olat.core.gui.components.Component.dispatchRequest(Component.java:183)
        at org.olat.core.gui.components.Window.doDispatchToComponent(Window.java:1127)
        at org.olat.core.gui.components.Window.dispatchRequest(Window.java:587)
        at org.olat.core.gui.components.Window.doDispatchRequest(Window.java:213)
        at org.olat.core.gui.components.Component.dispatchRequest(Component.java:183)
        at org.olat.dispatcher.AuthenticatedDispatcher.execute(AuthenticatedDispatcher.java:334)
        at org.olat.core.dispatcher.DispatcherAction.execute(DispatcherAction.java:157)
        at org.olat.core.servlets.OLATServlet.doPost(OLATServlet.java:138)
        at org.olat.core.servlets.OLATServlet.doGet(OLATServlet.java:112)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:617)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
        at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)
        at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191)
        at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127)
        at com.googlecode.psiprobe.Tomcat60AgentValve.invoke(Tomcat60AgentValve.java:30)
        at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
        at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:615)
        at org.apache.catalina.authenticator.SingleSignOn.invoke(SingleSignOn.java:394)
        at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
        at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:293)
        at org.apache.coyote.ajp.AjpAprProcessor.process(AjpAprProcessor.java:448)
        at org.apache.coyote.ajp.AjpAprProtocol$AjpConnectionHandler.process(AjpAprProtocol.java:399)
        at org.apache.tomcat.util.net.AprEndpoint$Worker.run(AprEndpoint.java:1675)
        at java.lang.Thread.run(Thread.java:662)
Caused by: com.mysql.jdbc.MysqlDataTruncation: Data truncation: Out of range value adjusted for column 'score' at row 1
        at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3561)
        at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3495)
        at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:1959)
        at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2113)
        at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2693)
        at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:2102)
        at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2395)
        at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2313)
        at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2298)
        at com.mchange.v2.c3p0.impl.NewProxyPreparedStatement.executeUpdate(NewProxyPreparedStatement.java:105)
        at org.hibernate.jdbc.NonBatchingBatcher.addToBatch(NonBatchingBatcher.java:46)
        at org.hibernate.persister.entity.AbstractEntityPersister.insert(AbstractEntityPersister.java:2265)
        ... 59 more
org.olat.core.logging.DBRuntimeException: Error in list()
        at org.olat.core.commons.persistence.DBQueryImpl.list(DBQueryImpl.java:180)
        at org.olat.course.assessment.NewCachePersistingAssessmentManager.loadPropertiesFor(NewCachePersistingAssessmentManager.java:177)
        at org.olat.course.assessment.NewCachePersistingAssessmentManager.getOrLoadScorePassedAttemptsMap(NewCachePersistingAssessmentManager.java:217)
        at org.olat.course.assessment.NewCachePersistingAssessmentManager.putPropertyIntoCache(NewCachePersistingAssessmentManager.java:256)
        at org.olat.course.assessment.NewCachePersistingAssessmentManager.saveNodeScore(NewCachePersistingAssessmentManager.java:345)
        at org.olat.course.assessment.NewCachePersistingAssessmentManager$5.execute(NewCachePersistingAssessmentManager.java:816)
        at org.olat.course.assessment.NewCachePersistingAssessmentManager$5.execute(NewCachePersistingAssessmentManager.java:811)
        at org.olat.commons.coordinate.cluster.ClusterSyncer.doInSync(ClusterSyncer.java:98)
        at org.olat.course.assessment.NewCachePersistingAssessmentManager.saveScoreEvaluation(NewCachePersistingAssessmentManager.java:811)
        at org.olat.course.nodes.IQTESTCourseNode.updateUserScoreEvaluation(IQTESTCourseNode.java:265)

        at org.olat.course.nodes.iq.IQRunController.event(IQRunController.java:474)
        at org.olat.core.gui.control.DefaultController$1.run(DefaultController.java:228)
        at org.olat.core.logging.activity.ThreadLocalUserActivityLoggerInstaller.runWithUserActivityLogger(ThreadLocalUserActivityLoggerInstaller.java:108)
        at org.olat.core.gui.control.DefaultController.dispatchEvent(DefaultController.java:225)
        at org.olat.core.gui.control.DefaultController.fireEvent(DefaultController.java:173)
        at org.olat.modules.iq.IQDisplayController.generateDetailsResults(IQDisplayController.java:555)
        at org.olat.modules.iq.IQDisplayController.postSubmitAssessment(IQDisplayController.java:539)
        at org.olat.modules.iq.IQDisplayController.event(IQDisplayController.java:496)
        at org.olat.core.gui.control.DefaultController.dispatchEvent(DefaultController.java:184)

        at org.olat.core.gui.components.Component$1.run(Component.java:229)
        at org.olat.core.logging.activity.ThreadLocalUserActivityLoggerInstaller.runWithUserActivityLogger(ThreadLocalUserActivityLoggerInstaller.java:108)
        at org.olat.core.gui.components.Component.fireEvent(Component.java:226)
        at org.olat.core.gui.components.velocity.VelocityContainer.doDispatchRequest(VelocityContainer.java:116)
        at org.olat.core.gui.components.Component.dispatchRequest(Component.java:183)
        at org.olat.core.gui.components.Window.doDispatchToComponent(Window.java:1127)
        at org.olat.core.gui.components.Window.dispatchRequest(Window.java:587)
        at org.olat.core.gui.components.Window.doDispatchRequest(Window.java:213)
        at org.olat.core.gui.components.Component.dispatchRequest(Component.java:183)
        at org.olat.dispatcher.AuthenticatedDispatcher.execute(AuthenticatedDispatcher.java:334)
        at org.olat.core.dispatcher.DispatcherAction.execute(DispatcherAction.java:157)
        at org.olat.core.servlets.OLATServlet.doPost(OLATServlet.java:138)
        at org.olat.core.servlets.OLATServlet.doGet(OLATServlet.java:112)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:617)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
        at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
        at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
        at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:233)
        at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:191)
        at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127)
        at com.googlecode.psiprobe.Tomcat60AgentValve.invoke(Tomcat60AgentValve.java:30)
        at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
        at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:615)
        at org.apache.catalina.authenticator.SingleSignOn.invoke(SingleSignOn.java:394)
        at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
        at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:293)
        at org.apache.coyote.ajp.AjpAprProcessor.process(AjpAprProcessor.java:448)
        at org.apache.coyote.ajp.AjpAprProtocol$AjpConnectionHandler.process(AjpAprProtocol.java:399)
        at org.apache.tomcat.util.net.AprEndpoint$Worker.run(AprEndpoint.java:1675)
        at java.lang.Thread.run(Thread.java:662)
Caused by: org.hibernate.exception.DataException: could not insert: [org.olat.ims.qti.QTIResultSet]
        at org.hibernate.exception.SQLStateConverter.convert(SQLStateConverter.java:100)
        at org.hibernate.exception.JDBCExceptionHelper.convert(JDBCExceptionHelper.java:66)
        at org.hibernate.persister.entity.AbstractEntityPersister.insert(AbstractEntityPersister.java:2285)
        at org.hibernate.persister.entity.AbstractEntityPersister.insert(AbstractEntityPersister.java:2678)
        at org.hibernate.action.EntityInsertAction.execute(EntityInsertAction.java:79)
        at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:279)
        at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:263)
        at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:167)
        at org.hibernate.event.def.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:321)
        at org.hibernate.event.def.DefaultAutoFlushEventListener.onAutoFlush(DefaultAutoFlushEventListener.java:64)
        at org.hibernate.impl.SessionImpl.autoFlushIfRequired(SessionImpl.java:997)
        at org.hibernate.impl.SessionImpl.list(SessionImpl.java:1142)
        at org.hibernate.impl.QueryImpl.list(QueryImpl.java:102)
        at org.olat.core.commons.persistence.DBQueryImpl.list(DBQueryImpl.java:125)
        ... 48 more
Caused by: com.mysql.jdbc.MysqlDataTruncation: Data truncation: Out of range value adjusted for column 'score' at row 1
        at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3561)
        at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3495)
        at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:1959)
        at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2113)
        at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2693)
        at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:2102)
        at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2395)
        at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2313)
        at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2298)
        at com.mchange.v2.c3p0.impl.NewProxyPreparedStatement.executeUpdate(NewProxyPreparedStatement.java:105)
        at org.hibernate.jdbc.NonBatchingBatcher.addToBatch(NonBatchingBatcher.java:46)
        at org.hibernate.persister.entity.AbstractEntityPersister.insert(AbstractEntityPersister.java:2265)
        ... 59 more

On Monday, September 17, 2012 11:57:42 AM UTC+2, glareff wrote:

Stéphane,

I attached another dummy that I created that also fails on our linux deployment.

Thanks,
Ilya

On Mon, Sep 17, 2012 at 10:43 AM, glareff <gla...@gmail.com> wrote:

Dear Stéphane,

Did you get my message to your email last week? I sent you the test + specifications of our system.

Thanks and regards,
Ilya

On Monday, September 17, 2012 10:31:13 AM UTC+2, Stéphane Rossé wrote:

Hi

Can you send me the test? It's important because I cannot reproduce the issue with our own tests. I must know the exact version of your OpenOLAT instance too as we have made some bug fixes in the QTI environment the last few months.

Stéphane

Le vendredi 14 septembre 2012 18:50:33 UTC+2, glareff a écrit :

even more testing reveals that the error occurs when the pasing score is 10/20 or more then the error occurs. If passing score is 7/20 for example it seems to work fine.

--
Sie erhalten diese Nachricht, weil Sie Mitglied sind von Google
Groups-Gruppe "OpenOLAT".
Für das Erstellen von Beiträgen in dieser Gruppe senden Sie eine E-Mail
an open...@googlegroups.com
Um sich von dieser Gruppe abzumelden, senden Sie eine E-Mail an

openolat+unsubscribe@googlegroups.com

[glareff]

QTIResultSet has the column score defined as a float.
It seems that manually changing this value in the db to anything over 10 will result in this error thrown by the DB. Is there any particular reason for using float instead of decimal or numeric on this column?

This seems to be related:
http://bugs.mysql.com/bug.php?id=7361

[Stéphane Rossé]

Hi

I try your test but cannot reproduce the error, this one or the first one. Which version of MySQL are you using? The bug in your link is pretty old and I hope you don't use MySQL 4.1.x, OpenOLAT need at least MySQL 5.0 and I would strongly recommend MySQL 5.1 or 5.5.

And for the float thing, the QTI implementation in Java use float and the mapping use it too.

Stéphane

openolat+u...@googlegroups.com

[glareff]

Hmm...sometimes my posts disappear, so here is a repost. I found the reason for the error - it was mysql 5.0 that was causing the problem. Upgrading to 5.5 solves it.