Grouper Users - Open Discussion List

[Rahul Doshi <>]

Hello,

We are having some database errors which is causing PSP to not update the groups correctly. Does anyone has any suggestions how I can avoid this error in the future and what is the best way to recover from this?

2013-03-11 09:09:54,094: [DefaultQuartzScheduler_Worker-9] ERROR JDBCExceptionReporter.logExceptions(234) -  - Duplicate entry '947053' for key 'PRIMARY'

2013-03-11 09:09:54,096: [DefaultQuartzScheduler_Worker-9] ERROR GrouperLoaderJob.runJob(406) -  - Error on job: CHANGE_LOG_changeLogTempToChangeLog

edu.internet2.middleware.grouper.internal.dao.GrouperDAOException: Problem in HibernateSession: HibernateSession: isNew: false, isReadonly: false, grouperTransactionType: READ_WRITE_NEW,

Exception in save: edu.internet2.middleware.grouper.changeLog.ChangeLogEntry, ByObjectStatic, query: ', cacheable: null, cacheRegion: null, entityName: ChangeLogEntryEntity, tx type: null,

Problem in HibernateSession: HibernateSession: isNew: true, isReadonly: false, grouperTransactionType: READ_WRITE_NEW

        at edu.internet2.middleware.grouper.hibernate.HibernateSession._internal_hibernateSessionCatch(HibernateSession.java:495)

        at edu.internet2.middleware.grouper.hibernate.HibernateSession.callbackHibernateSession(HibernateSession.java:581)

        at edu.internet2.middleware.grouper.hibernate.ByObjectStatic.save(ByObjectStatic.java:468)

        at edu.internet2.middleware.grouper.internal.dao.hib3.Hib3ChangeLogEntryDAO.save(Hib3ChangeLogEntryDAO.java:47)

        at edu.internet2.middleware.grouper.changeLog.ChangeLogEntry.save(ChangeLogEntry.java:356)

        at edu.internet2.middleware.grouper.changeLog.ChangeLogTempToEntity$1.callback(ChangeLogTempToEntity.java:196)

        at edu.internet2.middleware.grouper.hibernate.HibernateSession.callbackHibernateSession(HibernateSession.java:571)

        at edu.internet2.middleware.grouper.changeLog.ChangeLogTempToEntity.convertRecords(ChangeLogTempToEntity.java:91)

        at edu.internet2.middleware.grouper.app.loader.GrouperLoaderType$4.runJob(GrouperLoaderType.java:550)

        at edu.internet2.middleware.grouper.app.loader.GrouperLoaderJob.runJob(GrouperLoaderJob.java:398)

        at edu.internet2.middleware.grouper.app.loader.GrouperLoaderJob.execute(GrouperLoaderJob.java:300)

        at org.quartz.core.JobRunShell.run(JobRunShell.java:202)

        at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:529)

Caused by: org.hibernate.exception.ConstraintViolationException: Could not execute JDBC batch update,

Exception in save: edu.internet2.middleware.grouper.changeLog.ChangeLogEntry, edu.internet2.middleware.grouper.hibernate.ByObject@257f8345

        at org.hibernate.exception.SQLStateConverter.convert(SQLStateConverter.java:96)

        at org.hibernate.exception.JDBCExceptionHelper.convert(JDBCExceptionHelper.java:66)

        at org.hibernate.jdbc.AbstractBatcher.executeBatch(AbstractBatcher.java:275)

        at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:268)

        at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:184)

        at org.hibernate.event.def.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:321)

        at org.hibernate.event.def.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:51)

        at org.hibernate.impl.SessionImpl.flush(SessionImpl.java:1216)

        at edu.internet2.middleware.grouper.hibernate.ByObject.save(ByObject.java:214)

        at edu.internet2.middleware.grouper.hibernate.ByObjectStatic$7.callback(ByObjectStatic.java:481)

        at edu.internet2.middleware.grouper.hibernate.HibernateSession.callbackHibernateSession(HibernateSession.java:571)

        ... 11 more

Caused by: java.sql.BatchUpdateException: Duplicate entry '947053' for key 'PRIMARY'

        at com.mysql.jdbc.PreparedStatement.executeBatchSerially(PreparedStatement.java:2024)

        at com.mysql.jdbc.PreparedStatement.executeBatch(PreparedStatement.java:1449)

        at com.mchange.v2.c3p0.impl.NewProxyPreparedStatement.executeBatch(NewProxyPreparedStatement.java:1723)

        at org.hibernate.jdbc.BatchingBatcher.doExecuteBatch(BatchingBatcher.java:70)

        at org.hibernate.jdbc.AbstractBatcher.executeBatch(AbstractBatcher.java:268)

        ... 19 more

Caused by: com.mysql.jdbc.exceptions.jdbc4.MySQLIntegrityConstraintViolationException: Duplicate entry '947053' for key 'PRIMARY'

        at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)

        at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:57)

        at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)

        at java.lang.reflect.Constructor.newInstance(Constructor.java:532)

        at com.mysql.jdbc.Util.handleNewInstance(Util.java:411)

        at com.mysql.jdbc.Util.getInstance(Util.java:386)

        at com.mysql.jdbc.SQLError.createSQLException(SQLError.java:1039)

        at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3597)

        at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3529)

        at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:1990)

        at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2151)

        at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2625)

        at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:2119)

        at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2415)

        at com.mysql.jdbc.PreparedStatement.executeBatchSerially(PreparedStatement.java:1976)

        ... 23 more

2013-03-11 09:10:00,043: [DefaultQuartzScheduler_Worker-1] ERROR GrouperLoaderType$4.runJob(596) -  - Problem with change log consumer: psp

edu.internet2.middleware.grouper.exception.GrouperStaleObjectStateException: Problem in HibernateSession: HibernateSession: isNew: true, isReadonly: false, grouperTransactionType: READ_WRITE_NEW

        at edu.internet2.middleware.grouper.hibernate.HibernateSession._internal_hibernateSessionCatch(HibernateSession.java:491)

        at edu.internet2.middleware.grouper.hibernate.HibernateSession.callbackHibernateSession(HibernateSession.java:581)

        at edu.internet2.middleware.grouper.hibernate.ByObjectStatic.saveOrUpdate(ByObjectStatic.java:354)

        at edu.internet2.middleware.grouper.internal.dao.hib3.Hib3ChangeLogConsumerDAO.saveOrUpdate(Hib3ChangeLogConsumerDAO.java:41)

        at edu.internet2.middleware.grouper.changeLog.ChangeLogHelper.processRecords(ChangeLogHelper.java:258)

        at edu.internet2.middleware.grouper.app.loader.GrouperLoaderType$4.runJob(GrouperLoaderType.java:589)

        at edu.internet2.middleware.grouper.app.loader.GrouperLoaderJob.runJob(GrouperLoaderJob.java:398)

        at edu.internet2.middleware.grouper.app.loader.GrouperLoaderJob.execute(GrouperLoaderJob.java:300)

        at org.quartz.core.JobRunShell.run(JobRunShell.java:202)

        at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:529)

Caused by: org.hibernate.StaleObjectStateException: Row was updated or deleted by another transaction (or unsaved-value mapping was incorrect): [edu.internet2.middleware.grouper.changeLog.ChangeLogConsumer#8be8a00baea240eaae513780a4325886],

Exception in save: edu.internet2.middleware.grouper.changeLog.ChangeLogConsumer, edu.internet2.middleware.grouper.hibernate.ByObject@63e4a96f: [edu.internet2.middleware.grouper.changeLog.ChangeLogConsumer#8be8a00baea240eaae513780a4325886]

        at org.hibernate.persister.entity.AbstractEntityPersister.check(AbstractEntityPersister.java:1950)

        at org.hibernate.persister.entity.AbstractEntityPersister.update(AbstractEntityPersister.java:2594)

        at org.hibernate.persister.entity.AbstractEntityPersister.updateOrInsert(AbstractEntityPersister.java:2494)

        at org.hibernate.persister.entity.AbstractEntityPersister.update(AbstractEntityPersister.java:2821)

        at org.hibernate.action.EntityUpdateAction.execute(EntityUpdateAction.java:113)

        at org.hibernate.engine.ActionQueue.execute(ActionQueue.java:273)

        at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:265)

        at org.hibernate.engine.ActionQueue.executeActions(ActionQueue.java:185)

        at org.hibernate.event.def.AbstractFlushingEventListener.performExecutions(AbstractFlushingEventListener.java:321)

        at org.hibernate.event.def.DefaultFlushEventListener.onFlush(DefaultFlushEventListener.java:51)

        at org.hibernate.impl.SessionImpl.flush(SessionImpl.java:1216)

        at edu.internet2.middleware.grouper.hibernate.ByObject.saveOrUpdate(ByObject.java:377)

        at edu.internet2.middleware.grouper.hibernate.ByObjectStatic$5.callback(ByObjectStatic.java:367)

        at edu.internet2.middleware.grouper.hibernate.HibernateSession.callbackHibernateSession(HibernateSession.java:571)

        ... 8 more

2013-03-11 09:10:50,047: [DefaultQuartzScheduler_Worker-6] ERROR GrouperLoaderJob.runJob(406) -  - Error on job: CHANGE_LOG_changeLogTempToChangeLog

edu.internet2.middleware.grouper.internal.dao.GrouperDAOException: Problem in HibernateSession: HibernateSession: isNew: false, isReadonly: false, grouperTransactionType: READ_WRITE_NEW,

Exception in uniqueResult: (class edu.internet2.middleware.grouper.pit.PITMember), ByHqlStatic, query: 'select pitMember from PITMember as pitMember where pitMember.sourceId = :id and activeDb = 'T'', cacheable: true, cacheRegion: edu.internet2.middleware.grouper.internal.dao.hib3.Hib3PITMemberDAO.FindBySourceIdActive, tx type: null, tx type: nullBind var[0]: 

'Param (class java.lang.String): 'id'->'07058251fa4b46cba6cab0ecbe9a6d0d', ,

Problem in HibernateSession: HibernateSession: isNew: true, isReadonly: false, grouperTransactionType: READ_WRITE_NEW

        at edu.internet2.middleware.grouper.hibernate.HibernateSession._internal_hibernateSessionCatch(HibernateSession.java:495)

        at edu.internet2.middleware.grouper.hibernate.HibernateSession.callbackHibernateSession(HibernateSession.java:581)

        at edu.internet2.middleware.grouper.hibernate.ByHqlStatic.uniqueResult(ByHqlStatic.java:323)

        at edu.internet2.middleware.grouper.internal.dao.hib3.Hib3PITMemberDAO.findBySourceIdActive(Hib3PITMemberDAO.java:70)

        at edu.internet2.middleware.grouper.changeLog.ChangeLogTempToEntity.processMembershipAdd(ChangeLogTempToEntity.java:708)

        at edu.internet2.middleware.grouper.changeLog.ChangeLogTempToEntity.access$1700(ChangeLogTempToEntity.java:59)

        at edu.internet2.middleware.grouper.changeLog.ChangeLogTempToEntity$1.callback(ChangeLogTempToEntity.java:135)

        at edu.internet2.middleware.grouper.hibernate.HibernateSession.callbackHibernateSession(HibernateSession.java:571)

        at edu.internet2.middleware.grouper.changeLog.ChangeLogTempToEntity.convertRecords(ChangeLogTempToEntity.java:91)

        at edu.internet2.middleware.grouper.app.loader.GrouperLoaderType$4.runJob(GrouperLoaderType.java:550)

        at edu.internet2.middleware.grouper.app.loader.GrouperLoaderJob.runJob(GrouperLoaderJob.java:398)

        at edu.internet2.middleware.grouper.app.loader.GrouperLoaderJob.execute(GrouperLoaderJob.java:300)

        at org.quartz.core.JobRunShell.run(JobRunShell.java:202)

        at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:529)

Caused by: org.hibernate.NonUniqueResultException: query did not return a unique result: 3

        at org.hibernate.impl.AbstractQueryImpl.uniqueElement(AbstractQueryImpl.java:899)

        at org.hibernate.impl.AbstractQueryImpl.uniqueResult(AbstractQueryImpl.java:890)

        at edu.internet2.middleware.grouper.hibernate.ByHql.uniqueResult(ByHql.java:257)

        at edu.internet2.middleware.grouper.hibernate.ByHqlStatic$1.callback(ByHqlStatic.java:332)

        at edu.internet2.middleware.grouper.hibernate.HibernateSession.callbackHibernateSession(HibernateSession.java:571)

        ... 12 more

Thanks,

Rahul