member change subject tries to update effective membership

----Original Message----
From: Kumi Hagimoto
Sent: Wednesday, December 09, 2015 2:42 PM
To: grouper-users; Chris Hyzer
Subject: Re: RE: [grouper-users] Member Change Subject RuntimeException

Hi Chris,

Thanks for replying. Please see the stackTrace below from the
grouper_error.log:

Kumi

2015-12-09 11:26:02,077: [TP-Processor10] WARN WsSubjectLookup.retrieveSubjectIfNeeded(382) - < iam_grouper_admin_ws -
128.193.4.126 > - WsSubjectLookup[ subjectFindResult=SUCCESS, memberFindResult=SUCCESS,subjectId=pancakec]
edu.internet2.middleware.subject.SubjectNotFoundException: subject not found:pancakec
at edu.internet2.middleware.grouper.subj.SourcesXmlResolver.thereCanOnlyBeOne(SourcesXmlResolver.java:486)
at edu.internet2.middleware.grouper.subj.SourcesXmlResolver.find(SourcesXmlResolver.java:133)
at edu.internet2.middleware.grouper.subj.CachingResolver.find(CachingResolver.java:125)
at edu.internet2.middleware.grouper.subj.ValidatingResolver.find(ValidatingResolver.java:91)
at edu.internet2.middleware.grouper.SubjectFinder.findById(SubjectFinder.java:431)
at edu.internet2.middleware.grouper.ws.coresoap.WsSubjectLookup.retrieveSubjectIfNeeded(WsSubjectLookup.java:311)
at edu.internet2.middleware.grouper.ws.coresoap.WsSubjectLookup.retrieveSubject(WsSubjectLookup.java:486)
at edu.internet2.middleware.grouper.ws.coresoap.WsSubjectLookup.retrieveSubject(WsSubjectLookup.java:473)
at edu.internet2.middleware.grouper.ws.coresoap.WsSubjectLookup.retrieveMemberIfNeeded(WsSubjectLookup.java:404)
at edu.internet2.middleware.grouper.ws.coresoap.WsSubjectLookup.retrieveMember(WsSubjectLookup.java:499)
at edu.internet2.middleware.grouper.ws.GrouperServiceLogic.getMemberships(GrouperServiceLogic.java:1698)
at edu.internet2.middleware.grouper.ws.GrouperServiceLogic.getMembershipsLite(GrouperServiceLogic.java:1908)
at edu.internet2.middleware.grouper.ws.coresoap.GrouperService.getMembershipsLite(GrouperService.java:2539)
at edu.internet2.middleware.grouper.ws.rest.GrouperServiceRest.getMembershipsLite(GrouperServiceRest.java:1334)
at edu.internet2.middleware.grouper.ws.rest.method.GrouperWsRestGetSubject$2.service(GrouperWsRestGetSubject.java:106)
at edu.internet2.middleware.grouper.ws.rest.method.GrouperWsRestGet$6.service(GrouperWsRestGet.java:349)
at edu.internet2.middleware.grouper.ws.rest.method.GrouperRestHttpMethod$1.service(GrouperRestHttpMethod.java:57)
at edu.internet2.middleware.grouper.ws.rest.GrouperRestServlet.service(GrouperRestServlet.java:199)
at javax.servlet.http.HttpServlet.service(HttpServlet.java:717)
atorg.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:290)
at org.apache.catalina.core.ApplicationFilterChain.doFilter(ApplicationFilterChain.java:206)
at edu.internet2.middleware.grouper.ws.GrouperServiceJ2ee.doFilter(GrouperService
J2ee.java:661)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
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 org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:615)
at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:293)
at org.apache.jk.server.JkCoyoteHandler.invoke(JkCoyoteHandler.java:190)
at org.apache.jk.common.HandlerRequest.invoke(HandlerRequest.java:291)
at org.apache.jk.common.ChannelSocket.invoke(ChannelSocket.java:776)
at org.apache.jk.common.ChannelSocket.processConnection(ChannelSocket.java:705)
at org.apache.jk.common.ChannelSocket$SocketConnection.runIt(ChannelSocket.java:898)
at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:690)
at java.lang.Thread.run(Thread.java:745)

2015-12-09 11:26:02,332: [TP-Processor1] WARN
WsSubjectLookup.retrieveSubjectIfNeeded(382) - < iam_grouper_admin_ws -
128.193.4.126 > - WsSubjectLookup[
subjectFindResult=SUCCESS,
memberFindResult=SUCCESS,subjectId=pancakec]
edu.internet2.middleware.subject.SubjectNotFoundException: subject not found: pancakec
at edu.internet2.middleware.grouper.subj.SourcesXmlResolver.thereCanOnlyBeOne(SourcesXmlResolver.java:486)
at edu.internet2.middleware.grouper.subj.SourcesXmlResolver.find(SourcesXmlResolver.java:133)
at edu.internet2.middleware.grouper.subj.CachingResolver.find(CachingResolver.java:125)
at edu.internet2.middleware.grouper.subj.ValidatingResolver.find(ValidatingResolver.java:91)
at edu.internet2.middleware.grouper.SubjectFinder.findById(SubjectFinder.java:431)
at edu.internet2.middleware.grouper.ws.coresoap.WsSubjectLookup.retrieveSubjectIfNeeded(WsSubjectLookup.java:311)
at edu.internet2.middleware.grouper.ws.coresoap.WsSubjectLookup.retrieveSubject(WsSubjectLookup.java:486)
at edu.internet2.middleware.grouper.ws.coresoap.WsSubjectLookup.retrieveSubject(WsSubjectLookup.java:473)
at edu.internet2.middleware.grouper.ws.coresoap.WsSubjectLookup.retrieveMemberIfNeeded(WsSubjectLookup.java:404)
at edu.internet2.middleware.grouper.ws.coresoap.WsSubjectLookup.retrieveMember(WsSubjectLookup.java:499)
at edu.internet2.middleware.grouper.ws.GrouperServiceLogic$5.callback(GrouperServiceLogic.java:2719)
at edu.internet2.middleware.grouper.internal.dao.hib3.Hib3TransactionDAO$1.callback(Hib3TransactionDAO.java:66)
at edu.internet2.middleware.grouper.hibernate.HibernateSession.callbackHibernateSession(HibernateSession.java:668)
at edu.internet2.middleware.grouper.internal.dao.hib3.Hib3TransactionDAO.transactionCallback(Hib3TransactionDAO.java:56)
at edu.internet2.middleware.grouper.hibernate.GrouperTransaction.callbackGrouperTransaction(GrouperTransaction.java:87)
at edu.internet2.middleware.grouper.ws.GrouperServiceLogic.memberChangeSubject(GrouperServiceLogic.java:2695)
at edu.internet2.middleware.grouper.ws.GrouperServiceLogic.memberChangeSubjectLite(GrouperServiceLogic.java:2638)
at edu.internet2.middleware.grouper.ws.coresoap.GrouperService.memberChangeSubjectLite(GrouperService.java:1907)
at edu.internet2.middleware.grouper.ws.rest.GrouperServiceRest.memberChangeSubjectLite(GrouperServiceRest.java:660)
at edu.internet2.middleware.grouper.ws.rest.method.GrouperWsRestPut$5.service(GrouperWsRestPut.java:287)
at edu.internet2.middleware.grouper.ws.rest.method.GrouperRestHttpMethod$3.service(GrouperRestHttpMethod.java:104)
at edu.internet2.middleware.grouper.ws.rest.GrouperRestServlet.service(GrouperRestServlet.java:199)
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 edu.internet2.middleware.grouper.ws.GrouperServiceJ2ee.doFilter(GrouperServiceJ2ee.java:661)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
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 org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:615)
at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:293)
at org.apache.jk.server.JkCoyoteHandler.invoke(JkCoyoteHandler.java:190)
at org.apache.jk.common.HandlerRequest.invoke(HandlerRequest.java:291)
at org.apache.jk.common.ChannelSocket.invoke(ChannelSocket.java:776)
at org.apache.jk.common.ChannelSocket.processConnection(ChannelSocket.java:705)
at org.apache.jk.common.ChannelSocket$SocketConnection.runIt(ChannelSocket.java:898)
at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:690)
at java.lang.Thread.run(Thread.java:745)
2015-12-09 11:26:17,457: [TP-Processor1] ERROR
WsMemberChangeSubjectResult.assignResultCodeException(228) - <
iam_grouper_admin_ws - 128.193.4.126 > - WsMemberChangeSubject[
oldSubjectLookup=WsSubjectLookup[
member='pancakec'/'person'/'ldap',
cause=edu.internet2.middleware.subject.SubjectNotFoundException: subject not
found: pancakec,
subjectFindResult=SUBJECT_NOT_FOUND,
memberFindResult=SUCCESS,subjectId=pancakec],
newSubjectLookup=WsSubjectLookup[
subject=Subject id: pancake, sourceId: ldap, name: Pancake, Cherri M,
subjectFindResult=SUCCESS,subjectId=pancake],deleteOldMember=T],
java.lang.RuntimeException: Unexpected update attempt on effective
membership.,Exception in update: edu.internet2.middleware.grouper.Membership,
edu.internet2.middleware.grouper.hibernate.ByObject@168872f1,
Problem in HibernateSession: HibernateSession (2cbdeae): notNew, notReadonly,
READ_WRITE_NEW, activeTransaction, session (583237c5),
Exception in update: edu.internet2.middleware.grouper.Membership,
ByObjectStatic, query: ', cacheable: null, cacheRegion: null, entityName:
ImmediateMembershipEntry, tx type: null,
, this subject: Subject id: pancakec, sourceId: ldap, new subject: Subject id:
pancake, sourceId: ldap, deleteOldMember: true, report? false,
Problem in HibernateSession: HibernateSession (76f23f0f): new, notReadonly,
READ_WRITE_NEW, notActiveTransaction, session (583237c5)
java.lang.RuntimeException: Unexpected update attempt on effective
membership.,
Exception in update: edu.internet2.middleware.grouper.Membership,
edu.internet2.middleware.grouper.hibernate.ByObject@168872f1,
Problem in HibernateSession: HibernateSession (2cbdeae): notNew, notReadonly,
READ_WRITE_NEW, activeTransaction, session (583237c5),
Exception in update: edu.internet2.middleware.grouper.Membership,
ByObjectStatic, query: ', cacheable: null, cacheRegion: null, entityName:
ImmediateMembershipEntry, tx type: null,
, this subject: Subject id: pancakec, sourceId: ldap, new subject: Subject id:
pancake, sourceId: ldap, deleteOldMember: true, report? false,
Problem in HibernateSession: HibernateSession (76f23f0f): new, notReadonly,
READ_WRITE_NEW, notActiveTransaction, session (583237c5)
at edu.internet2.middleware.grouper.Membership.onPreUpdate(Membership.java:2395)
at edu.internet2.middleware.grouper.hibernate.ByObject.update(ByObject.java:497)
at edu.internet2.middleware.grouper.hibernate.ByObjectStatic$2.callback(ByObjectStatic.java:211)
at edu.internet2.middleware.grouper.hibernate.HibernateSession.callbackHibernateSession(HibernateSession.java:668)
at edu.internet2.middleware.grouper.hibernate.ByObjectStatic.update(ByObjectStatic.java:196)
at edu.internet2.middleware.grouper.internal.dao.hib3.Hib3MembershipDAO.update(Hib3MembershipDAO.java:2083)
at edu.internet2.middleware.grouper.internal.dao.hib3.Hib3MembershipDAO.update(Hib3MembershipDAO.java:2104)
at edu.internet2.middleware.grouper.Member$1.callback(Member.java:727)
at edu.internet2.middleware.grouper.hibernate.HibernateSession.callbackHibernateSession(HibernateSession.java:668)
at edu.internet2.middleware.grouper.Member.changeSubjectHelper(Member.java:578)
at edu.internet2.middleware.grouper.Member.changeSubject(Member.java:481)
at edu.internet2.middleware.grouper.ws.GrouperServiceLogic$5.callback(GrouperServiceLogic.java:2737)
at edu.internet2.middleware.grouper.internal.dao.hib3.Hib3TransactionDAO$1.callback(Hib3TransactionDAO.java:66)
at edu.internet2.middleware.grouper.hibernate.HibernateSession.callbackHibernateSession(HibernateSession.java:668)
at edu.internet2.middleware.grouper.internal.dao.hib3.Hib3TransactionDAO.transactionCallback(Hib3TransactionDAO.java:56)
at edu.internet2.middleware.grouper.hibernate.GrouperTransaction.callbackGrouperTransaction(GrouperTransaction.java:87)
at edu.internet2.middleware.grouper.ws.GrouperServiceLogic.memberChangeSubject(GrouperServiceLogic.java:2695)
at edu.internet2.middleware.grouper.ws.GrouperServiceLogic.memberChangeSubjectLite(GrouperServiceLogic.java:2638)
at edu.internet2.middleware.grouper.ws.coresoap.GrouperService.memberChangeSubjectLite(GrouperService.java:1907)
at edu.internet2.middleware.grouper.ws.rest.GrouperServiceRest.memberChangeSubjectLite(GrouperServiceRest.java:660)
at edu.internet2.middleware.grouper.ws.rest.method.GrouperWsRestPut$5.service(GrouperWsRestPut.java:287)
at edu.internet2.middleware.grouper.ws.rest.method.GrouperRestHttpMethod$3.service(GrouperRestHttpMethod.java:104)
at edu.internet2.middleware.grouper.ws.rest.GrouperRestServlet.service(GrouperRestServlet.java:199)
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 edu.internet2.middleware.grouper.ws.GrouperServiceJ2ee.doFilter(GrouperServiceJ2ee.java:661)
at org.apache.catalina.core.ApplicationFilterChain.internalDoFilter(ApplicationFilterChain.java:235)
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 org.apache.catalina.valves.ErrorReportValve.invoke(ErrorReportValve.java:102)
at org.apache.catalina.valves.AccessLogValve.invoke(AccessLogValve.java:615)
at org.apache.catalina.core.StandardEngineValve.invoke(StandardEngineValve.java:109)
at org.apache.catalina.connector.CoyoteAdapter.service(CoyoteAdapter.java:293)
at org.apache.jk.server.JkCoyoteHandler.invoke(JkCoyoteHandler.java:190)
at org.apache.jk.common.HandlerRequest.invoke(HandlerRequest.java:291)
at org.apache.jk.common.ChannelSocket.invoke(ChannelSocket.java:776)
at org.apache.jk.common.ChannelSocket.processConnection(ChannelSocket.java:705)
at org.apache.jk.common.ChannelSocket$SocketConnection.runIt(ChannelSocket.java:898)
at org.apache.tomcat.util.threads.ThreadPool$ControlRunnable.run(ThreadPool.java:690)
at java.lang.Thread.run(Thread.java:745)