[Building Sakai] oracle deadlock exception with Gradebook queries
Zhen Qian
zqian at umich.edu
Tue Sep 6 15:07:15 PDT 2011
Hi, all:
We are running Sakai 2.7.1 code base in UMich. Last weekend, we found several GB related deadlock stacktrace messages in server log.
Also our dba reported the following queries causes high db contentions:
"insert into GB_GRADABLE_OBJECT_T (VERSION, GRADEBOOK_ID, NAME, REMOVED, SORT_ORDER, OBJECT_TYPE_ID, ID) values (:1 , :2 , :3 , :4 , :5 , :"SYS_B_0", :6 )
delete from GB_GRADABLE_OBJECT_T where ID=:1 and VERSION=:2
insert into GB_GRADABLE_OBJECT_T (VERSION, GRADEBOOK_ID, NAME, REMOVED, SORT_ORDER, POINTS_POSSIBLE, DUE_DATE, NOT_COUNTED, EXTERNALLY_MAINTAINED,EXTERNAL_STUDENT_LINK, EXTERNAL_INSTRUCTOR_LINK, EXTERNAL_ID, EXTERNAL_APP_NAME, IS_EXTRA_CREDIT, ASSIGNMENT_WEIGHTING, RELEASED, CATEGORY_ID, UNGRADED,IS_NULL_ZERO, OBJECT_TYPE_ID, ID) values (:1 , :2 , :3 , :4 , :5 , :6 , :7 , :8 , :9 , :10 , :11 , :...
insert into GB_GRADEBOOK_T (VERSION, GRADEBOOK_UID, NAME, SELECTED_GRADE_MAPPING_ID, ASSIGNMENTS_DISPLAYED, COURSE_GRADE_DISPLAYED,ALL_ASSIGNMENTS_ENTERED, LOCKED, GRADE_TYPE, CATEGORY_TYPE, IS_EQUAL_WEIGHT_CATS, IS_SCALED_EXTRA_CREDIT, DO_SHOW_MEAN, DO_SHOW_MEDIAN, DO_SHOW_MODE,DO_SHOW_RANK, DO_SHOW_ITEM_STATS, ID) values (:1 , :2 , :3 , :4 , :5 , :6 , :7 , :8 , :9 , :10 , :11 , :12 , :13 , :14..."
I found an ancient jira: SAK-7407 about data contention caused by Gradebook tool, but it is marked as fixed for 2.4 and the reported queries are different from those above.
I am not sure whether this is a new problem or not? Could someone with more knowledge about Gradebook tool give some insights here?
Thanks,
- Zhen
p.s. the stack trace of db deadlock problem:
Sep 5 20:10:54 10.211.253.106 [TP-Processor5] ERROR org.hibernate.util.JDBCExceptionReporter - ORA-00060: deadlock detected while waiting for resource
Sep 5 20:10:54 10.211.253.106 [TP-Processor5] ERROR org.hibernate.event.def.AbstractFlushingEventListener - Could not synchronize database state with session
Sep 5 20:10:54 10.211.253.106 org.hibernate.exception.LockAcquisitionException: could not delete: [org.sakaiproject.tool.gradebook.Assignment#199272]
Sep 5 20:10:54 10.211.253.106 at org.hibernate.exception.SQLStateConverter.convert(SQLStateConverter.java:87)
Sep 5 20:10:54 10.211.253.106 at org.hibernate.exception.JDBCExceptionHelper.convert(JDBCExceptionHelper.java:43)
Sep 5 20:10:54 10.211.253.106 at org.hibernate.persister.entity.AbstractEntityPersister.delete(AbstractEntityPersister.java:2536)
Sep 5 20:10:54 10.211.253.106 at org.hibernate.persister.entity.AbstractEntityPersister.delete(AbstractEntityPersister.java:2692)
Sep 5 20:10:54 10.211.253.106 at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:77)
Sep 5 20:10:54 10.211.253.106 at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:279)
Sep 5 20:10:54 10.211.253.106 at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:263)
Sep 5 20:10:54 10.211.253.106 at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:172)
Sep 5 20:10:54 10.211.253.106 at org.hibernate.event.def.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:298)
Sep 5 20:10:54 10.211.253.106 at org.hibernate.event.def.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:27)
Sep 5 20:10:54 10.211.253.106 at org.hibernate.impl.SessionImpl.flush(SessionImpl.java:1001)
Sep 5 20:10:54 10.211.253.106 at org.springframework.orm.hibernate3.HibernateTemplate$28.doInHibernate(HibernateTemplate.java:892)
Sep 5 20:10:54 10.211.253.106 at org.springframework.orm.hibernate3.HibernateTemplate.doExecute(HibernateTemplate.java:419)
Sep 5 20:10:54 10.211.253.106 at org.springframework.orm.hibernate3.HibernateTemplate.executeWithNativeSession(HibernateTemplate.java:374)
Sep 5 20:10:54 10.211.253.106 at org.springframework.orm.hibernate3.HibernateTemplate.flush(HibernateTemplate.java:890)
Sep 5 20:10:54 10.211.253.106 at org.sakaiproject.component.gradebook.GradebookFrameworkServiceImpl.deleteGradebook(GradebookFrameworkServiceImpl.java:290)
Sep 5 20:10:54 10.211.253.106 at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
Sep 5 20:10:54 10.211.253.106 at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
Sep 5 20:10:54 10.211.253.106 at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
Sep 5 20:10:54 10.211.253.106 at java.lang.reflect.Method.invoke(Method.java:597)
Sep 5 20:10:54 10.211.253.106 at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
Sep 5 20:10:54 10.211.253.106 at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:182)
Sep 5 20:10:54 10.211.253.106 at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:149)
Sep 5 20:10:54 10.211.253.106 at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:106)
Sep 5 20:10:55 10.211.253.106 at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171)
Sep 5 20:10:55 10.211.253.106 at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
Sep 5 20:10:55 10.211.253.106 at $Proxy32.deleteGradebook(Unknown Source)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.tool.gradebook.facades.sakai2impl.GradebookEntityProducer.contextDeleted(GradebookEntityProducer.java:94)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.site.impl.BaseSiteService.disableRelated(BaseSiteService.java:2031)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.site.impl.BaseSiteService.removeSite(BaseSiteService.java:1202)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.site.cover.SiteService.removeSite(SiteService.java:245)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.site.tool.SiteAction.doSite_delete_confirmed(SiteAction.java:4051)
Sep 5 20:10:55 10.211.253.106 at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
Sep 5 20:10:55 10.211.253.106 at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
Sep 5 20:10:55 10.211.253.106 at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
Sep 5 20:10:55 10.211.253.106 at java.lang.reflect.Method.invoke(Method.java:597)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.cheftool.VelocityPortletPaneledAction.actionDispatch(VelocityPortletPaneledAction.java:625)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.cheftool.VelocityPortletPaneledAction.processAction(VelocityPortletPaneledAction.java:535)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.cheftool.ToolServlet.doGet(ToolServlet.java:230)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.cheftool.VelocityPortletPaneledAction.doGet(VelocityPortletPaneledAction.java:1032)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.cheftool.ToolServlet.doPost(ToolServlet.java:154)
Sep 5 20:10:55 10.211.253.106 at javax.servlet.http.HttpServlet.service(HttpServlet.java:647)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.vm.ComponentServlet.service(ComponentServlet.java:56)
Sep 5 20:10:55 10.211.253.106 at javax.servlet.http.HttpServlet.service(HttpServlet.java:729)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:269)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.util.RequestFilter.doFilter(RequestFilter.java:597)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.ApplicationDispatcher.invoke(ApplicationDispatcher.java:659)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.ApplicationDispatcher.processRequest(ApplicationDispatcher.java:457)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.ApplicationDispatcher.doForward(ApplicationDispatcher.java:359)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.ApplicationDispatcher.forward(ApplicationDispatcher.java:311)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.tool.impl.ActiveToolComponent$MyActiveTool.forward(ActiveToolComponent.java:512)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.portal.charon.SkinnableCharonPortal.forwardTool(SkinnableCharonPortal.java:1350)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.portal.charon.handlers.ToolHandler.doTool(ToolHandler.java:204)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.portal.charon.handlers.ToolHandler.doGet(ToolHandler.java:96)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.portal.charon.handlers.ToolHandler.doPost(ToolHandler.java:73)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.portal.charon.SkinnableCharonPortal.doPost(SkinnableCharonPortal.java:1177)
Sep 5 20:10:55 10.211.253.106 at javax.servlet.http.HttpServlet.service(HttpServlet.java:647)
Sep 5 20:10:55 10.211.253.106 at javax.servlet.http.HttpServlet.service(HttpServlet.java:729)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:269)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.util.RequestFilter.doFilter(RequestFilter.java:658)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:172)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:117)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:581)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:108)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:174)
Sep 5 20:10:55 10.211.253.106 at org.apache.jk.server.JkCoyoteHandler.invoke(JkCoyoteHandler.java:200)
Sep 5 20:10:55 10.211.253.106 at org.apache.jk.common.HandlerRequest.invoke(HandlerRequest.java:291)
Sep 5 20:10:55 10.211.253.106 at org.apache.jk.common.ChannelSocket.invoke(ChannelSocket.java:775)
Sep 5 20:10:55 10.211.253.106 at org.apache.jk.common.ChannelSocket.processConnection(ChannelSocket.java:704)
Sep 5 20:10:55 10.211.253.106 at org.apache.jk.common.ChannelSocket$SocketConnection.runIt(ChannelSocket.java:897)
Sep 5 20:10:55 10.211.253.106 at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:689)
Sep 5 20:10:55 10.211.253.106 at java.lang.Thread.run(Thread.java:662)
Sep 5 20:10:55 10.211.253.106 aused by: java.sql.SQLException: ORA-00060: deadlock detected while waiting for resource
Sep 5 20:10:55 10.211.253.106 at oracle.jdbc.driver.T4CTTIoer.processError(T4CTTIoer.java:440)
Sep 5 20:10:55 10.211.253.106 at oracle.jdbc.driver.T4CTTIoer.processError(T4CTTIoer.java:396)
Sep 5 20:10:55 10.211.253.106 at oracle.jdbc.driver.T4C8Oall.processError(T4C8Oall.java:837)
Sep 5 20:10:55 10.211.253.106 at oracle.jdbc.driver.T4CTTIfun.receive(T4CTTIfun.java:445)
Sep 5 20:10:55 10.211.253.106 at oracle.jdbc.driver.T4CTTIfun.doRPC(T4CTTIfun.java:191)
Sep 5 20:10:55 10.211.253.106 at oracle.jdbc.driver.T4C8Oall.doOALL(T4C8Oall.java:523)
Sep 5 20:10:55 10.211.253.106 at oracle.jdbc.driver.T4CPreparedStatement.doOall8(T4CPreparedStatement.java:207)
Sep 5 20:10:55 10.211.253.106 at oracle.jdbc.driver.T4CPreparedStatement.executeForRows(T4CPreparedStatement.java:1010)
Sep 5 20:10:55 10.211.253.106 at oracle.jdbc.driver.OracleStatement.doExecuteWithTimeout(OracleStatement.java:1315)
Sep 5 20:10:55 10.211.253.106 at oracle.jdbc.driver.OraclePreparedStatement.executeInternal(OraclePreparedStatement.java:3576)
Sep 5 20:10:55 10.211.253.106 at oracle.jdbc.driver.OraclePreparedStatement.executeUpdate(OraclePreparedStatement.java:3657)
Sep 5 20:10:55 10.211.253.106 at oracle.jdbc.driver.OraclePreparedStatementWrapper.executeUpdate(OraclePreparedStatementWrapper.java:1350)
Sep 5 20:10:55 10.211.253.106 at org.apache.commons.dbcp.DelegatingPreparedStatement.executeUpdate(DelegatingPreparedStatement.java:102)
Sep 5 20:10:55 10.211.253.106 at org.hibernate.persister.entity.AbstractEntityPersister.delete(AbstractEntityPersister.java:2518)
Sep 5 20:10:55 10.211.253.106 ... 77 more
Sep 5 20:10:55 10.211.253.106 [TP-Processor5] WARN org.sakaiproject.site.impl.BaseSiteService - Error encountered while notifying ContextObserver of Site Change
Sep 5 20:10:55 10.211.253.106 org.springframework.dao.CannotAcquireLockException: could not delete: [org.sakaiproject.tool.gradebook.Assignment#199272]; nested exception is org.hibernate.exception.LockAcquisitionException: could not delete: [org.sakaiproject.tool.gradebook.Assignment#199272]
Sep 5 20:10:55 10.211.253.106 at org.springframework.orm.hibernate3.SessionFactoryUtils.convertHibernateAccessException(SessionFactoryUtils.java:633)
Sep 5 20:10:55 10.211.253.106 at org.springframework.orm.hibernate3.HibernateAccessor.convertHibernateAccessException(HibernateAccessor.java:412)
Sep 5 20:10:55 10.211.253.106 at org.springframework.orm.hibernate3.HibernateTemplate.doExecute(HibernateTemplate.java:424)
Sep 5 20:10:55 10.211.253.106 at org.springframework.orm.hibernate3.HibernateTemplate.executeWithNativeSession(HibernateTemplate.java:374)
Sep 5 20:10:55 10.211.253.106 at org.springframework.orm.hibernate3.HibernateTemplate.flush(HibernateTemplate.java:890)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.component.gradebook.GradebookFrameworkServiceImpl.deleteGradebook(GradebookFrameworkServiceImpl.java:290)
Sep 5 20:10:55 10.211.253.106 at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
Sep 5 20:10:55 10.211.253.106 at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
Sep 5 20:10:55 10.211.253.106 at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
Sep 5 20:10:55 10.211.253.106 at java.lang.reflect.Method.invoke(Method.java:597)
Sep 5 20:10:55 10.211.253.106 at org.springframework.aop.support.AopUtils.invokeJoinpointUsingReflection(AopUtils.java:307)
Sep 5 20:10:55 10.211.253.106 at org.springframework.aop.framework.ReflectiveMethodInvocation.invokeJoinpoint(ReflectiveMethodInvocation.java:182)
Sep 5 20:10:55 10.211.253.106 at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:149)
Sep 5 20:10:55 10.211.253.106 at org.springframework.transaction.interceptor.TransactionInterceptor.invoke(TransactionInterceptor.java:106)
Sep 5 20:10:55 10.211.253.106 at org.springframework.aop.framework.ReflectiveMethodInvocation.proceed(ReflectiveMethodInvocation.java:171)
Sep 5 20:10:55 10.211.253.106 at org.springframework.aop.framework.JdkDynamicAopProxy.invoke(JdkDynamicAopProxy.java:204)
Sep 5 20:10:55 10.211.253.106 at $Proxy32.deleteGradebook(Unknown Source)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.tool.gradebook.facades.sakai2impl.GradebookEntityProducer.contextDeleted(GradebookEntityProducer.java:94)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.site.impl.BaseSiteService.disableRelated(BaseSiteService.java:2031)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.site.impl.BaseSiteService.removeSite(BaseSiteService.java:1202)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.site.cover.SiteService.removeSite(SiteService.java:245)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.site.tool.SiteAction.doSite_delete_confirmed(SiteAction.java:4051)
Sep 5 20:10:55 10.211.253.106 at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
Sep 5 20:10:55 10.211.253.106 at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
Sep 5 20:10:55 10.211.253.106 at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
Sep 5 20:10:55 10.211.253.106 at java.lang.reflect.Method.invoke(Method.java:597)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.cheftool.VelocityPortletPaneledAction.actionDispatch(VelocityPortletPaneledAction.java:625)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.cheftool.VelocityPortletPaneledAction.processAction(VelocityPortletPaneledAction.java:535)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.cheftool.ToolServlet.doGet(ToolServlet.java:230)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.cheftool.VelocityPortletPaneledAction.doGet(VelocityPortletPaneledAction.java:1032)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.cheftool.ToolServlet.doPost(ToolServlet.java:154)
Sep 5 20:10:55 10.211.253.106 at javax.servlet.http.HttpServlet.service(HttpServlet.java:647)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.vm.ComponentServlet.service(ComponentServlet.java:56)
Sep 5 20:10:55 10.211.253.106 at javax.servlet.http.HttpServlet.service(HttpServlet.java:729)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:269)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.util.RequestFilter.doFilter(RequestFilter.java:597)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.ApplicationDispatcher.invoke(ApplicationDispatcher.java:659)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.ApplicationDispatcher.processRequest(ApplicationDispatcher.java:457)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.ApplicationDispatcher.doForward(ApplicationDispatcher.java:359)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.ApplicationDispatcher.forward(ApplicationDispatcher.java:311)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.tool.impl.ActiveToolComponent$MyActiveTool.forward(ActiveToolComponent.java:512)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.portal.charon.SkinnableCharonPortal.forwardTool(SkinnableCharonPortal.java:1350)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.portal.charon.handlers.ToolHandler.doTool(ToolHandler.java:204)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.portal.charon.handlers.ToolHandler.doGet(ToolHandler.java:96)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.portal.charon.handlers.ToolHandler.doPost(ToolHandler.java:73)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.portal.charon.SkinnableCharonPortal.doPost(SkinnableCharonPortal.java:1177)
Sep 5 20:10:55 10.211.253.106 at javax.servlet.http.HttpServlet.service(HttpServlet.java:647)
Sep 5 20:10:55 10.211.253.106 at javax.servlet.http.HttpServlet.service(HttpServlet.java:729)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:269)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)
Sep 5 20:10:55 10.211.253.106 at org.sakaiproject.util.RequestFilter.doFilter(RequestFilter.java:658)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:215)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:188)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.StandardWrapperValve.invoke(StandardWrapperValve.java:213)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.StandardContextValve.invoke(StandardContextValve.java:172)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.StandardHostValve.invoke(StandardHostValve.java:127)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:117)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:581)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:108)
Sep 5 20:10:55 10.211.253.106 at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:174)
Sep 5 20:10:55 10.211.253.106 at org.apache.jk.server.JkCoyoteHandler.invoke(JkCoyoteHandler.java:200)
Sep 5 20:10:55 10.211.253.106 at org.apache.jk.common.HandlerRequest.invoke(HandlerRequest.java:291)
Sep 5 20:10:55 10.211.253.106 at org.apache.jk.common.ChannelSocket.invoke(ChannelSocket.java:775)
Sep 5 20:10:55 10.211.253.106 at org.apache.jk.common.ChannelSocket.processConnection(ChannelSocket.java:704)
Sep 5 20:10:55 10.211.253.106 at org.apache.jk.common.ChannelSocket$SocketConnection.runIt(ChannelSocket.java:897)
Sep 5 20:10:55 10.211.253.106 at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:689)
Sep 5 20:10:55 10.211.253.106 at java.lang.Thread.run(Thread.java:662)
Sep 5 20:10:55 10.211.253.106 aused by: org.hibernate.exception.LockAcquisitionException: could not delete: [org.sakaiproject.tool.gradebook.Assignment#199272]
Sep 5 20:10:55 10.211.253.106 at org.hibernate.exception.SQLStateConverter.convert(SQLStateConverter.java:87)
Sep 5 20:10:55 10.211.253.106 at org.hibernate.exception.JDBCExceptionHelper.convert(JDBCExceptionHelper.java:43)
Sep 5 20:10:55 10.211.253.106 at org.hibernate.persister.entity.AbstractEntityPersister.delete(AbstractEntityPersister.java:2536)
Sep 5 20:10:55 10.211.253.106 at org.hibernate.persister.entity.AbstractEntityPersister.delete(AbstractEntityPersister.java:2692)
Sep 5 20:10:55 10.211.253.106 at org.hibernate.action.EntityDeleteAction.execute(EntityDeleteAction.java:77)
Sep 5 20:10:55 10.211.253.106 at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:279)
Sep 5 20:10:55 10.211.253.106 at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:263)
Sep 5 20:10:55 10.211.253.106 at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:172)
Sep 5 20:10:55 10.211.253.106 at org.hibernate.event.def.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:298)
Sep 5 20:10:55 10.211.253.106 at org.hibernate.event.def.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:27)
Sep 5 20:10:55 10.211.253.106 at org.hibernate.impl.SessionImpl.flush(SessionImpl.java:1001)
Sep 5 20:10:55 10.211.253.106 at org.springframework.orm.hibernate3.HibernateTemplate$28.doInHibernate(HibernateTemplate.java:892)
Sep 5 20:10:55 10.211.253.106 at org.springframework.orm.hibernate3.HibernateTemplate.doExecute(HibernateTemplate.java:419)
Sep 5 20:10:55 10.211.253.106 ... 67 more
Sep 5 20:10:55 10.211.253.106 aused by: java.sql.SQLException: ORA-00060: deadlock detected while waiting for resource
Sep 5 20:10:55 10.211.253.106 at oracle.jdbc.driver.T4CTTIoer.processError(T4CTTIoer.java:440)
Sep 5 20:10:55 10.211.253.106 at oracle.jdbc.driver.T4CTTIoer.processError(T4CTTIoer.java:396)
Sep 5 20:10:55 10.211.253.106 at oracle.jdbc.driver.T4C8Oall.processError(T4C8Oall.java:837)
Sep 5 20:10:55 10.211.253.106 at oracle.jdbc.driver.T4CTTIfun.receive(T4CTTIfun.java:445)
Sep 5 20:10:55 10.211.253.106 at oracle.jdbc.driver.T4CTTIfun.doRPC(T4CTTIfun.java:191)
Sep 5 20:10:55 10.211.253.106 at oracle.jdbc.driver.T4C8Oall.doOALL(T4C8Oall.java:523)
Sep 5 20:10:55 10.211.253.106 at oracle.jdbc.driver.T4CPreparedStatement.doOall8(T4CPreparedStatement.java:207)
Sep 5 20:10:55 10.211.253.106 at oracle.jdbc.driver.T4CPreparedStatement.executeForRows(T4CPreparedStatement.java:1010)
Sep 5 20:10:55 10.211.253.106 at oracle.jdbc.driver.OracleStatement.doExecuteWithTimeout(OracleStatement.java:1315)
Sep 5 20:10:55 10.211.253.106 at oracle.jdbc.driver.OraclePreparedStatement.executeInternal(OraclePreparedStatement.java:3576)
Sep 5 20:10:55 10.211.253.106 at oracle.jdbc.driver.OraclePreparedStatement.executeUpdate(OraclePreparedStatement.java:3657)
Sep 5 20:10:55 10.211.253.106 at oracle.jdbc.driver.OraclePreparedStatementWrapper.executeUpdate(OraclePreparedStatementWrapper.java:1350)
Sep 5 20:10:55 10.211.253.106 at org.apache.commons.dbcp.DelegatingPreparedStatement.executeUpdate(DelegatingPreparedStatement.java:102)
Sep 5 20:10:55 10.211.253.106 at org.hibernate.persister.entity.AbstractEntityPersister.delete(AbstractEntityPersister.java:2518)
Sep 5 20:10:55 10.211.253.106 ... 77 more
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://collab.sakaiproject.org/pipermail/sakai-dev/attachments/20110906/47d95875/attachment.html
More information about the sakai-dev
mailing list