2012-06-20 09:20:00,036: [DefaultQuartzScheduler_Worker-9] INFO PspChangeLogConsumer.processChangeLogEntry(513) - - PSP Consumer 'psp' - Change log entry 'ChangeLogEntry[timestamp=2012-06-20 09:19:38.082,sequence=1579,category=membership,actionname=addMembership,contextId=0f4423af784f4ab78ac1d85d44a9014f,id=abe292662fad451b8a45db1998e7167b,fieldName=members,subjectId=gagns,sourceId=ldap,membershipType=flattened,groupId=5b1f781179d24219b5f676fe99196fc5,groupName=sad:TEST,memberId=c101ba7d634d4b9086bf6b0ec459acd0,fieldId=eee8e39c0c2949ae8627ea8013648868]' 2012-06-20 09:20:00,036: [DefaultQuartzScheduler_Worker-9] INFO Psp.execute(885) - - Psp 'psp' - Calc CalcRequest[id=change_log_sequence_number:1579,requestID=2012/06/20-09:20:00.036,returnData=everything] 2012-06-20 09:20:00,037: [DefaultQuartzScheduler_Worker-9] INFO Psp.execute(889) - - Psp 'psp' - Calc XML: <psp:calcRequest xmlns:psp='http://grouper.internet2.edu/psp' requestID='2012/06/20-09:20:00.036' returnData='everything'> <psp:id ID='change_log_sequence_number:1579'/> </psp:calcRequest> 2012-06-20 09:20:00,051: [DefaultQuartzScheduler_Worker-9] WARN ShibbolethAttributeResolver.resolveAttribute(319) - - null requested attribute groupDescription but no attribute definition exists for that attribute 2012-06-20 09:20:00,055: [DefaultQuartzScheduler_Worker-9] WARN AbstractLdap.operationRetry(1105) - - Error performing LDAP operation, retrying (attempt 0) javax.naming.CommunicationException: Connection reset [Root exception is java.net.SocketException: Connection reset]; remaining name 'OU=People,DC=devsim,DC=umontreal,DC=ca' at com.sun.jndi.ldap.LdapCtx.doSearch(LdapCtx.java:1983) at com.sun.jndi.ldap.LdapCtx.searchAux(LdapCtx.java:1827) at com.sun.jndi.ldap.LdapCtx.c_search(LdapCtx.java:1752) at com.sun.jndi.ldap.LdapCtx.c_search(LdapCtx.java:1769) at com.sun.jndi.toolkit.ctx.ComponentDirContext.p_search(ComponentDirContext.java:394) at com.sun.jndi.toolkit.ctx.PartialCompositeDirContext.search(PartialCompositeDirContext.java:376) at com.sun.jndi.toolkit.ctx.PartialCompositeDirContext.search(PartialCompositeDirContext.java:358) at edu.vt.middleware.ldap.AbstractLdap.search(AbstractLdap.java:215) at edu.vt.middleware.ldap.Ldap.search(Ldap.java:431) at edu.vt.middleware.ldap.Ldap.search(Ldap.java:347) at edu.internet2.middleware.subject.provider.LdapSourceAdapter.getLdapResultsHelper(LdapSourceAdapter.java:628) at edu.internet2.middleware.subject.provider.LdapSourceAdapter.getLdapResults(LdapSourceAdapter.java:555) at edu.internet2.middleware.subject.provider.LdapSourceAdapter.getLdapUnique(LdapSourceAdapter.java:651) at edu.internet2.middleware.subject.provider.LdapSourceAdapter.getSubject(LdapSourceAdapter.java:289) at edu.internet2.middleware.grouper.subj.SourcesXmlResolver.find(SourcesXmlResolver.java:295) at edu.internet2.middleware.grouper.subj.CachingResolver.find(CachingResolver.java:128) at edu.internet2.middleware.grouper.subj.ValidatingResolver.find(ValidatingResolver.java:89) at edu.internet2.middleware.grouper.SubjectFinder.findByIdAndSource(SubjectFinder.java:295) at edu.internet2.middleware.psp.shibboleth.ChangeLogDataConnector.buildSubjectAttributes(ChangeLogDataConnector.java:306) at edu.internet2.middleware.psp.shibboleth.ChangeLogDataConnector.buildAttributes(ChangeLogDataConnector.java:169) at edu.internet2.middleware.psp.shibboleth.ChangeLogDataConnector.resolve(ChangeLogDataConnector.java:106) at edu.internet2.middleware.psp.shibboleth.ChangeLogDataConnector.resolve(ChangeLogDataConnector.java:54) at edu.internet2.middleware.shibboleth.common.attribute.resolver.provider.dataConnector.ContextualDataConnector.resolve(ContextualDataConnector.java:77) at edu.internet2.middleware.shibboleth.common.attribute.resolver.provider.dataConnector.ContextualDataConnector.resolve(ContextualDataConnector.java:31) at edu.internet2.middleware.shibboleth.common.attribute.resolver.provider.ShibbolethAttributeResolver.resolveDataConnector(ShibbolethAttributeResolver.java:374) at edu.internet2.middleware.shibboleth.common.attribute.resolver.provider.ShibbolethAttributeResolver.resolveDependencies(ShibbolethAttributeResolver.java:410) at edu.internet2.middleware.shibboleth.common.attribute.resolver.provider.ShibbolethAttributeResolver.resolveAttribute(ShibbolethAttributeResolver.java:332) at edu.internet2.middleware.shibboleth.common.attribute.resolver.provider.ShibbolethAttributeResolver.resolveDependencies(ShibbolethAttributeResolver.java:412) at edu.internet2.middleware.shibboleth.common.attribute.resolver.provider.ShibbolethAttributeResolver.resolveAttribute(ShibbolethAttributeResolver.java:332) at edu.internet2.middleware.shibboleth.common.attribute.resolver.provider.ShibbolethAttributeResolver.resolveAttributes(ShibbolethAttributeResolver.java:284) at edu.internet2.middleware.shibboleth.common.attribute.resolver.provider.ShibbolethAttributeResolver.resolveAttributes(ShibbolethAttributeResolver.java:131) at edu.internet2.middleware.psp.shibboleth.SimpleAttributeAuthority.getAttributes(SimpleAttributeAuthority.java:96) at edu.internet2.middleware.psp.shibboleth.SimpleAttributeAuthority.getAttributes(SimpleAttributeAuthority.java:39) at edu.internet2.middleware.psp.Psp.execute(Psp.java:972) at edu.internet2.middleware.psp.Psp.execute(Psp.java:909) at edu.internet2.middleware.psp.grouper.PspChangeLogConsumer.processModification(PspChangeLogConsumer.java:704) at edu.internet2.middleware.psp.grouper.PspChangeLogConsumer.processMembershipAdd(PspChangeLogConsumer.java:655) at edu.internet2.middleware.psp.grouper.PspChangeLogConsumer$EventType$6.process(PspChangeLogConsumer.java:126) at edu.internet2.middleware.psp.grouper.PspChangeLogConsumer.processChangeLogEntry(PspChangeLogConsumer.java:517) at edu.internet2.middleware.psp.grouper.PspChangeLogConsumer.processChangeLogEntries(PspChangeLogConsumer.java:451) at edu.internet2.middleware.grouper.changeLog.ChangeLogHelper.processRecords(ChangeLogHelper.java:204) at edu.internet2.middleware.grouper.app.loader.GrouperLoaderType$4.runJob(GrouperLoaderType.java:539) at edu.internet2.middleware.grouper.app.loader.GrouperLoaderJob.runJob(GrouperLoaderJob.java:385) at edu.internet2.middleware.grouper.app.loader.GrouperLoaderJob.execute(GrouperLoaderJob.java:287) at org.quartz.core.JobRunShell.run(JobRunShell.java:202) at org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:529) Caused by: java.net.SocketException: Connection reset at java.net.SocketInputStream.read(SocketInputStream.java:168) at com.sun.net.ssl.internal.ssl.InputRecord.readFully(InputRecord.java:293) at com.sun.net.ssl.internal.ssl.InputRecord.read(InputRecord.java:331) at com.sun.net.ssl.internal.ssl.SSLSocketImpl.readRecord(SSLSocketImpl.java:830) at com.sun.net.ssl.internal.ssl.SSLSocketImpl.readDataRecord(SSLSocketImpl.java:787) at com.sun.net.ssl.internal.ssl.AppInputStream.read(AppInputStream.java:75) at java.io.BufferedInputStream.fill(BufferedInputStream.java:218) at java.io.BufferedInputStream.read1(BufferedInputStream.java:258) at java.io.BufferedInputStream.read(BufferedInputStream.java:317) at com.sun.jndi.ldap.Connection.run(Connection.java:820) at java.lang.Thread.run(Thread.java:662) 2012-06-20 09:20:00,495: [DefaultQuartzScheduler_Worker-9] ERROR Psp.execute(920) - - Psp 'psp' - Calc CalcResponse[status=failure,error=noSuchIdentifier,errorMessages={Unable to calculate provisioned object.},requestID=2012/06/20-09:20:00.036] 2012-06-20 09:20:00,495: [DefaultQuartzScheduler_Worker-9] ERROR Psp.execute(922) - - Psp 'psp' - Calc XML: <psp:calcResponse xmlns:psp='http://grouper.internet2.edu/psp' status='failure' requestID='2012/06/20-09:20:00.036' error='noSuchIdentifier'> <errorMessage>Unable to calculate provisioned object.</errorMessage> <psp:id ID='change_log_sequence_number:1579'/> </psp:calcResponse>