Here's the new log. I set it to trace just so I could convince myself everything was being seen. It is to a point.
As a comparison. I looked at what was happening when the description attribute was changed. Here are the relevant differences.
Below is the more complete log as relates to displayExtension changes.
7f,id=a7e7fd636f314fbe9fa4464f31440517,name=edu:berkeley:app:calmessages:deans,parentStemId=f175cea0b6b741d185ca2cb3059d9dbd,displayName=edu:berkeley:app:Cal Messages:Deans 9,description=description 14,propertyChanged=displayExtension,propertyOldValue=Deans 8,propertyNewValue=Deans 9]'
2013-10-03 19:11:00,028: [DefaultQuartzScheduler_Worker-6] DEBUG PspChangeLogConsumer.processGroupUpdate(689) - - PSP Consumer 'psp' - Change log entry 'ChangeLogEntry[timestamp=2013-10-03 19:10:30.76,sequence=685336,category=group,actionname=updateGroup,contextId=e9f72d51126645cb88e060d05b35da7f]
' 013-10-03 19:11:00,028: [DefaultQuartzScheduler_Worker-6] DEBUG PspChangeLogConsumer.processUpdate(1013) - - PSP Consumer 'psp' - Change log entry 'ChangeLogEntry[timestamp=2013-10-03 19:10:30.76,sequence=685336,category=group,actionname=updateGroup,contextId=e9f72d51126645cb88e060d05b35da7f]' 2013-10-03 19:11:00,029: [DefaultQuartzScheduler_Worker-6] INFO Psp.execute(980) - - Psp 'psp' - Calc CalcRequest[id=change_log_sequence_number:685336,requestID=2013/10/03-19:11:00.028,returnData=data]
2013-10-03 19:11:00,029: [DefaultQuartzScheduler_Worker-2] INFO EventLog.info(156) - - [0276d426dfdd4a7f8747453d0351a8b2,'GrouperSystem','application'] session: start (0ms)
2013-10-03 19:11:00,031: [DefaultQuartzScheduler_Worker-6] INFO Psp.execute(984) - - Psp 'psp' - Calc XML:
<psp:id ID='change_log_sequence_number:685336'/>
</psp:calcRequest>
2013-10-03 19:11:00,031: [DefaultQuartzScheduler_Worker-6] TRACE Psp.getTargetAndObjectDefinitions(1932) - - PSP 'psp' - Get pso definitions for schema entity 'SchemaEntityRef[targetID=<null>,entityName=<null>,isContainer=false]'
2013-10-03 19:11:00,031: [DefaultQuartzScheduler_Worker-6] TRACE Psp.getTargetAndObjectDefinitions(1986) - - PSP 'psp' - Get pso definitions for schema entity 'SchemaEntityRef[targetID=<null>,entityName=<null>,isContainer=false]' found {ldap=[Pso[id=stem,authoritative=true], Pso[id=group,authorita
tive=true], Pso[id=member,authoritative=false], Pso[id=groupMembership,authoritative=false]]}
2013-10-03 19:11:00,031: [DefaultQuartzScheduler_Worker-6] TRACE Psp.getTargetAndObjectDefinitions(1932) - - PSP 'psp' - Get pso definitions for schema entity 'SchemaEntityRef[targetID=<null>,entityName=<null>,isContainer=false]'
2013-10-03 19:11:00,032: [DefaultQuartzScheduler_Worker-6] TRACE Psp.getTargetAndObjectDefinitions(1986) - - PSP 'psp' - Get pso definitions for schema entity 'SchemaEntityRef[targetID=<null>,entityName=<null>,isContainer=false]' found {ldap=[Pso[id=stem,authoritative=true], Pso[id=group,authorita
tive=true], Pso[id=member,authoritative=false], Pso[id=groupMembership,authoritative=false]]}
2013-10-03 19:11:00,032: [DefaultQuartzScheduler_Worker-6] DEBUG Psp.execute(1069) - - PSP 'psp' - Calc CalcRequest[id=change_log_sequence_number:685336,requestID=2013/10/03-19:11:00.028,returnData=data] Resolving attributes '[stemDn, stemDnAlternateChangeLog, stemObjectclass, stemOu, stemDescript
ion, groupDn, groupDnAlternate, groupDnAlternateChangeLog, groupObjectclass, cn, groupDescription, groupDisplayName, memberDn, changeLogMembershipGroupDn]'.
2013-10-03 19:11:00,032: [DefaultQuartzScheduler_Worker-6] DEBUG SimpleAttributeAuthority.getAttributes(86) - - get attributes 'change_log_sequence_number:685336' aa 'psp.AttributeAuthority'
2013-10-03 19:11:00,032: [DefaultQuartzScheduler_Worker-6] TRACE SimpleAttributeAuthority.getAttributes(90) - - resolve get attributes 'change_log_sequence_number:685336' aa 'psp.AttributeAuthority' requested attribute 'stemDn'
2013-10-03 19:11:00,032: [DefaultQuartzScheduler_Worker-6] TRACE SimpleAttributeAuthority.getAttributes(90) - - resolve get attributes 'change_log_sequence_number:685336' aa 'psp.AttributeAuthority' requested attribute 'stemDnAlternateChangeLog'
2013-10-03 19:11:00,032: [DefaultQuartzScheduler_Worker-6] TRACE SimpleAttributeAuthority.getAttributes(90) - - resolve get attributes 'change_log_sequence_number:685336' aa 'psp.AttributeAuthority' requested attribute 'stemObjectclass'
2013-10-03 19:11:00,032: [DefaultQuartzScheduler_Worker-6] TRACE SimpleAttributeAuthority.getAttributes(90) - - resolve get attributes 'change_log_sequence_number:685336' aa 'psp.AttributeAuthority' requested attribute 'stemOu'
2013-10-03 19:11:00,033: [DefaultQuartzScheduler_Worker-6] TRACE SimpleAttributeAuthority.getAttributes(90) - - resolve get attributes 'change_log_sequence_number:685336' aa 'psp.AttributeAuthority' requested attribute 'stemDescription'
2013-10-03 19:11:00,033: [DefaultQuartzScheduler_Worker-6] TRACE SimpleAttributeAuthority.getAttributes(90) - - resolve get attributes 'change_log_sequence_number:685336' aa 'psp.AttributeAuthority' requested attribute 'groupDn'
2013-10-03 19:11:00,033: [DefaultQuartzScheduler_Worker-6] TRACE SimpleAttributeAuthority.getAttributes(90) - - resolve get attributes 'change_log_sequence_number:685336' aa 'psp.AttributeAuthority' requested attribute 'groupDnAlternate'
2013-10-03 19:11:00,033: [DefaultQuartzScheduler_Worker-6] TRACE SimpleAttributeAuthority.getAttributes(90) - - resolve get attributes 'change_log_sequence_number:685336' aa 'psp.AttributeAuthority' requested attribute 'groupDnAlternateChangeLog'
2013-10-03 19:11:00,033: [DefaultQuartzScheduler_Worker-6] TRACE SimpleAttributeAuthority.getAttributes(90) - - resolve get attributes 'change_log_sequence_number:685336' aa 'psp.AttributeAuthority' requested attribute 'groupObjectclass'
2013-10-03 19:11:00,033: [DefaultQuartzScheduler_Worker-6] TRACE SimpleAttributeAuthority.getAttributes(90) - - resolve get attributes 'change_log_sequence_number:685336' aa 'psp.AttributeAuthority' requested attribute 'cn'
2013-10-03 19:11:00,033: [DefaultQuartzScheduler_Worker-6] TRACE SimpleAttributeAuthority.getAttributes(90) - - resolve get attributes 'change_log_sequence_number:685336' aa 'psp.AttributeAuthority' requested attribute 'groupDescription'
2013-10-03 19:11:00,033: [DefaultQuartzScheduler_Worker-6] TRACE SimpleAttributeAuthority.getAttributes(90) - - resolve get attributes 'change_log_sequence_number:685336' aa 'psp.AttributeAuthority' requested attribute 'groupDisplayName'
2013-10-03 19:11:00,034: [DefaultQuartzScheduler_Worker-6] TRACE SimpleAttributeAuthority.getAttributes(90) - - resolve get attributes 'change_log_sequence_number:685336' aa 'psp.AttributeAuthority' requested attribute 'memberDn'
2013-10-03 19:11:00,034: [DefaultQuartzScheduler_Worker-6] TRACE SimpleAttributeAuthority.getAttributes(90) - - resolve get attributes 'change_log_sequence_number:685336' aa 'psp.AttributeAuthority' requested attribute 'changeLogMembershipGroupDn'
2013-10-03 19:11:00,034: [DefaultQuartzScheduler_Worker-6] DEBUG StemDataConnector$1.callback(76) - - Stem data connector 'StemDataConnector' - Resolve principal 'change_log_sequence_number:685336'
2013-10-03 19:11:00,034: [DefaultQuartzScheduler_Worker-6] TRACE StemDataConnector$1.callback(77) - - Stem data connector 'StemDataConnector' - Resolve principal 'change_log_sequence_number:685336' requested attributes [stemDn, stemDnAlternateChangeLog, stemObjectclass, stemOu, stemDescription, gr
oupDn, groupDnAlternate, groupDnAlternateChangeLog, groupObjectclass, cn, groupDescription, groupDisplayName, memberDn, changeLogMembershipGroupDn]
2013-10-03 19:11:00,034: [DefaultQuartzScheduler_Worker-6] DEBUG StemDataConnector$1.callback(81) - - Stem data connector 'StemDataConnector' - Ignoring principal name 'change_log_sequence_number:685336'
2013-10-03 19:11:00,034: [DefaultQuartzScheduler_Worker-6] DEBUG ChangeLogDataConnector.resolve(68) - - ChangeLog data connector 'DeleteStemChangeLogDataConnector' - Resolve principal 'change_log_sequence_number:685336'
2013-10-03 19:11:00,034: [DefaultQuartzScheduler_Worker-6] TRACE ChangeLogDataConnector.resolve(69) - - ChangeLog data connector 'DeleteStemChangeLogDataConnector' - Resolve principal 'change_log_sequence_number:685336' requested attributes [stemDn, stemDnAlternateChangeLog, stemObjectclass, stemO
u, stemDescription, groupDn, groupDnAlternate, groupDnAlternateChangeLog, groupObjectclass, cn, groupDescription, groupDisplayName, memberDn, changeLogMembershipGroupDn]
Group update, name: edu:berkeley:app:calmessages:deans, property: displayExtension, from: 'Deans 8', to: 'Deans 9'
2013-10-03 19:11:00,040: [DefaultQuartzScheduler_Worker-6] DEBUG ChangeLogDataConnector.resolve(98) - - ChangeLog data connector 'DeleteStemChangeLogDataConnector' - Ignoring changelog 'ChangeLogEntry[timestamp=2013-10-03 19:10:30.76,sequence=685336,category=group,actionname=updateGroup,contextId=
e9f72d51126645cb88e060d05b35da7f]'
2013-10-03 19:11:00,041: [DefaultQuartzScheduler_Worker-6] DEBUG ChangeLogDataConnector.resolve(68) - - ChangeLog data connector 'UpdateStemNameChangeLogDataConnector' - Resolve principal 'change_log_sequence_number:685336'
2013-10-03 19:11:00,041: [DefaultQuartzScheduler_Worker-6] TRACE ChangeLogDataConnector.resolve(69) - - ChangeLog data connector 'UpdateStemNameChangeLogDataConnector' - Resolve principal 'change_log_sequence_number:685336' requested attributes [stemDn, stemDnAlternateChangeLog, stemObjectclass, s
temOu, stemDescription, groupDn, groupDnAlternate, groupDnAlternateChangeLog, groupObjectclass, cn, groupDescription, groupDisplayName, memberDn, changeLogMembershipGroupDn]
2013-10-03 19:11:00,045: [DefaultQuartzScheduler_Worker-6] DEBUG ChangeLogDataConnector.resolve(98) - - ChangeLog data connector 'UpdateStemNameChangeLogDataConnector' - Ignoring changelog 'ChangeLogEntry[timestamp=2013-10-03 19:10:30.76,sequence=685336,category=group,actionname=updateGroup,contex
tId=e9f72d51126645cb88e060d05b35da7f]'
2013-10-03 19:11:00,046: [DefaultQuartzScheduler_Worker-6] DEBUG ChangeLogDataConnector.resolve(68) - - ChangeLog data connector 'UpdateStemDescriptionChangeLogDataConnector' - Resolve principal 'change_log_sequence_number:685336'
2013-10-03 19:11:00,046: [DefaultQuartzScheduler_Worker-6] TRACE ChangeLogDataConnector.resolve(69) - - ChangeLog data connector 'UpdateStemDescriptionChangeLogDataConnector' - Resolve principal 'change_log_sequence_number:685336' requested attributes [stemDn, stemDnAlternateChangeLog, stemObjectc
lass, stemOu, stemDescription, groupDn, groupDnAlternate, groupDnAlternateChangeLog, groupObjectclass, cn, groupDescription, groupDisplayName, memberDn, changeLogMembershipGroupDn]
2013-10-03 19:11:00,050: [DefaultQuartzScheduler_Worker-6] DEBUG ChangeLogDataConnector.resolve(98) - - ChangeLog data connector 'UpdateStemDescriptionChangeLogDataConnector' - Ignoring changelog 'ChangeLogEntry[timestamp=2013-10-03 19:10:30.76,sequence=685336,category=group,actionname=updateGroup
,contextId=e9f72d51126645cb88e060d05b35da7f]'
2013-10-03 19:11:00,050: [DefaultQuartzScheduler_Worker-6] DEBUG FilteredNameAttributeDefinition.doResolve(59) - - Filtered name attribute definition 'stemNameInStem' - Resolve principal 'change_log_sequence_number:685336'
2013-10-03 19:11:00,051: [DefaultQuartzScheduler_Worker-6] DEBUG LdapDnFromGrouperNamePSOIdentifierAttributeDefinition.doResolve(189) - - Ldap dn from grouper name attribute definition 'stemDn' - Resolve principal 'change_log_sequence_number:685336'
2013-10-03 19:11:00,051: [DefaultQuartzScheduler_Worker-6] DEBUG LdapDnFromGrouperNamePSOIdentifierAttributeDefinition.doResolve(197) - - Ldap dn from grouper name attribute definition 'stemDn' - Resolve principal 'change_log_sequence_number:685336' No dependencies
2013-10-03 19:11:00,051: [DefaultQuartzScheduler_Worker-6] DEBUG LdapDnFromGrouperNamePSOIdentifierAttributeDefinition.doResolve(189) - - Ldap dn from grouper name attribute definition 'stemDnAlternateChangeLog' - Resolve principal 'change_log_sequence_number:685336'
2013-10-03 19:11:00,052: [DefaultQuartzScheduler_Worker-6] DEBUG LdapDnFromGrouperNamePSOIdentifierAttributeDefinition.doResolve(197) - - Ldap dn from grouper name attribute definition 'stemDnAlternateChangeLog' - Resolve principal 'change_log_sequence_number:685336' No dependencies
2013-10-03 19:11:00,055: [DefaultQuartzScheduler_Worker-6] DEBUG GroupDataConnector$1.callback(85) - - Group data connector 'GroupDataConnector' - Resolve principal 'change_log_sequence_number:685336'
2013-10-03 19:11:00,056: [DefaultQuartzScheduler_Worker-6] TRACE GroupDataConnector$1.callback(86) - - Group data connector 'GroupDataConnector' - Resolve principal 'change_log_sequence_number:685336' requested attributes [stemDn, stemDnAlternateChangeLog, stemObjectclass, stemOu, stemDescription,
groupDn, groupDnAlternate, groupDnAlternateChangeLog, groupObjectclass, cn, groupDescription, groupDisplayName, memberDn, changeLogMembershipGroupDn]
2013-10-03 19:11:00,056: [DefaultQuartzScheduler_Worker-6] DEBUG GroupDataConnector$1.callback(90) - - Group data connector 'GroupDataConnector' - Ignoring principal name 'change_log_sequence_number:685336'
2013-10-03 19:11:00,056: [DefaultQuartzScheduler_Worker-6] DEBUG ChangeLogDataConnector.resolve(68) - - ChangeLog data connector 'DeleteGroupChangeLogDataConnector' - Resolve principal 'change_log_sequence_number:685336'
2013-10-03 19:11:00,056: [DefaultQuartzScheduler_Worker-6] TRACE ChangeLogDataConnector.resolve(69) - - ChangeLog data connector 'DeleteGroupChangeLogDataConnector' - Resolve principal 'change_log_sequence_number:685336' requested attributes [stemDn, stemDnAlternateChangeLog, stemObjectclass, stem
Ou, stemDescription, groupDn, groupDnAlternate, groupDnAlternateChangeLog, groupObjectclass, cn, groupDescription, groupDisplayName, memberDn, changeLogMembershipGroupDn]
2013-10-03 19:11:00,061: [DefaultQuartzScheduler_Worker-6] DEBUG ChangeLogDataConnector.resolve(98) - - ChangeLog data connector 'DeleteGroupChangeLogDataConnector' - Ignoring changelog 'ChangeLogEntry[timestamp=2013-10-03 19:10:30.76,sequence=685336,category=group,actionname=updateGroup,contextId
=e9f72d51126645cb88e060d05b35da7f]'
2013-10-03 19:11:00,061: [DefaultQuartzScheduler_Worker-6] DEBUG ChangeLogDataConnector.resolve(68) - - ChangeLog data connector 'UpdateGroupNameChangeLogDataConnector' - Resolve principal 'change_log_sequence_number:685336'
2013-10-03 19:11:00,061: [DefaultQuartzScheduler_Worker-6] TRACE ChangeLogDataConnector.resolve(69) - - ChangeLog data connector 'UpdateGroupNameChangeLogDataConnector' - Resolve principal 'change_log_sequence_number:685336' requested attributes [stemDn, stemDnAlternateChangeLog, stemObjectclass,
stemOu, stemDescription, groupDn, groupDnAlternate, groupDnAlternateChangeLog, groupObjectclass, cn, groupDescription, groupDisplayName, memberDn, changeLogMembershipGroupDn]
2013-10-03 19:11:00,066: [DefaultQuartzScheduler_Worker-6] DEBUG ChangeLogDataConnector.resolve(98) - - ChangeLog data connector 'UpdateGroupNameChangeLogDataConnector' - Ignoring changelog 'ChangeLogEntry[timestamp=2013-10-03 19:10:30.76,sequence=685336,category=group,actionname=updateGroup,conte
xtId=e9f72d51126645cb88e060d05b35da7f]'
2013-10-03 19:11:00,066: [DefaultQuartzScheduler_Worker-6] DEBUG ChangeLogDataConnector.resolve(68) - - ChangeLog data connector 'UpdateGroupDescriptionChangeLogDataConnector' - Resolve principal 'change_log_sequence_number:685336'
2013-10-03 19:11:00,066: [DefaultQuartzScheduler_Worker-6] TRACE ChangeLogDataConnector.resolve(69) - - ChangeLog data connector 'UpdateGroupDescriptionChangeLogDataConnector' - Resolve principal 'change_log_sequence_number:685336' requested attributes [stemDn, stemDnAlternateChangeLog, stemObject
class, stemOu, stemDescription, groupDn, groupDnAlternate, groupDnAlternateChangeLog, groupObjectclass, cn, groupDescription, groupDisplayName, memberDn, changeLogMembershipGroupDn]
2013-10-03 19:11:00,070: [DefaultQuartzScheduler_Worker-6] DEBUG ChangeLogDataConnector.resolve(98) - - ChangeLog data connector 'UpdateGroupDescriptionChangeLogDataConnector' - Ignoring changelog 'ChangeLogEntry[timestamp=2013-10-03 19:10:30.76,sequence=685336,category=group,actionname=updateGrou
p,contextId=e9f72d51126645cb88e060d05b35da7f]'
2013-10-03 19:11:00,071: [DefaultQuartzScheduler_Worker-6] DEBUG ChangeLogDataConnector.resolve(68) - - ChangeLog data connector 'UpdateGroupDisplayNameChangeLogDataConnector' - Resolve principal 'change_log_sequence_number:685336'
2013-10-03 19:11:00,071: [DefaultQuartzScheduler_Worker-6] TRACE ChangeLogDataConnector.resolve(69) - - ChangeLog data connector 'UpdateGroupDisplayNameChangeLogDataConnector' - Resolve principal 'change_log_sequence_number:685336' requested attributes [stemDn, stemDnAlternateChangeLog, stemObject
class, stemOu, stemDescription, groupDn, groupDnAlternate, groupDnAlternateChangeLog, groupObjectclass, cn, groupDescription, groupDisplayName, memberDn, changeLogMembershipGroupDn]
2013-10-03 19:11:00,075: [DefaultQuartzScheduler_Worker-6] DEBUG ChangeLogDataConnector.resolve(102) - - ChangeLog data connector 'UpdateGroupDisplayNameChangeLogDataConnector' - Found change log entry 'ChangeLogEntry[timestamp=2013-10-03 19:10:30.76,sequence=685336,category=group,actionname=updat
eGroup,contextId=e9f72d51126645cb88e060d05b35da7f]'
2013-10-03 19:11:00,076: [DefaultQuartzScheduler_Worker-6] TRACE ChangeLogDataConnector.resolve(103) - - ChangeLog data connector 'UpdateGroupDisplayNameChangeLogDataConnector' - Found change log entry 'ChangeLogEntry[timestamp=2013-10-03 19:10:30.76,sequence=685336,category=group,actionname=updat
eGroup,contextId=e9f72d51126645cb88e060d05b35da7f,id=a7e7fd636f314fbe9fa4464f31440517,name=edu:berkeley:app:calmessages:deans,parentStemId=f175cea0b6b741d185ca2cb3059d9dbd,displayName=edu:berkeley:app:Cal Messages:Deans 9,description=description 14,propertyChanged=displayExtension,propertyOldValue=
Deans 8,propertyNewValue=Deans 9]'
2013-10-03 19:11:00,076: [DefaultQuartzScheduler_Worker-6] TRACE ChangeLogDataConnector.resolve(104) - - ChangeLog data connector 'UpdateGroupDisplayNameChangeLogDataConnector' - Found change log entry 'ChangeLogEntry: changeLogTypeId: 'e43950442bf34ea888c9b7404f0b0bbe', contextId: 'e9f72d51126645
cb88e060d05b35da7f', createdOnDb: '1380852630760000', hibernateVersionNumber: '-1', string01: 'a7e7fd636f314fbe9fa4464f31440517', string02: 'edu:berkeley:app:calmessages:deans', string03: 'f175cea0b6b741d185ca2cb3059d9dbd', string04: 'edu:berkeley:app:Cal Messages:Deans 9', string05: 'description 1
4', string06: 'displayExtension', string07: 'Deans 8', string08: 'Deans 9''
2013-10-03 19:11:00,076: [DefaultQuartzScheduler_Worker-6] DEBUG ChangeLogDataConnector.resolve(108) - - ChangeLog data connector 'UpdateGroupDisplayNameChangeLogDataConnector' - Change log entry ChangeLogEntry[timestamp=2013-10-03 19:10:30.76,sequence=685336,category=group,actionname=updateGroup,
contextId=e9f72d51126645cb88e060d05b35da7f] returning {id=id, name=name, parentStemId=parentStemId, displayName=displayName, description=description, propertyChanged=propertyChanged, propertyOldValue=propertyOldValue, propertyNewValue=propertyNewValue, actionName=actionName, changeLogCategory=chang
eLogCategory, sequenceNumber=sequenceNumber, createdOn=createdOn}
2013-10-03 19:11:00,077: [DefaultQuartzScheduler_Worker-6] TRACE ChangeLogDataConnector.resolve(114) - - ChangeLog data connector 'UpdateGroupDisplayNameChangeLogDataConnector' - Change log entry ChangeLogEntry[timestamp=2013-10-03 19:10:30.76,sequence=685336,category=group,actionname=updateGroup,
contextId=e9f72d51126645cb88e060d05b35da7f] attribute id : a7e7fd636f314fbe9fa4464f31440517
2013-10-03 19:11:00,077: [DefaultQuartzScheduler_Worker-6] TRACE ChangeLogDataConnector.resolve(114) - - ChangeLog data connector 'UpdateGroupDisplayNameChangeLogDataConnector' - Change log entry ChangeLogEntry[timestamp=2013-10-03 19:10:30.76,sequence=685336,category=group,actionname=updateGroup,
contextId=e9f72d51126645cb88e060d05b35da7f] attribute name : edu:berkeley:app:calmessages:deans
2013-10-03 19:11:00,077: [DefaultQuartzScheduler_Worker-6] TRACE ChangeLogDataConnector.resolve(114) - - ChangeLog data connector 'UpdateGroupDisplayNameChangeLogDataConnector' - Change log entry ChangeLogEntry[timestamp=2013-10-03 19:10:30.76,sequence=685336,category=group,actionname=updateGroup,
contextId=e9f72d51126645cb88e060d05b35da7f] attribute parentStemId : f175cea0b6b741d185ca2cb3059d9dbd
2013-10-03 19:11:00,077: [DefaultQuartzScheduler_Worker-6] TRACE ChangeLogDataConnector.resolve(114) - - ChangeLog data connector 'UpdateGroupDisplayNameChangeLogDataConnector' - Change log entry ChangeLogEntry[timestamp=2013-10-03 19:10:30.76,sequence=685336,category=group,actionname=updateGroup,
contextId=e9f72d51126645cb88e060d05b35da7f] attribute displayName : edu:berkeley:app:Cal Messages:Deans 9
2013-10-03 19:11:00,077: [DefaultQuartzScheduler_Worker-6] TRACE ChangeLogDataConnector.resolve(114) - - ChangeLog data connector 'UpdateGroupDisplayNameChangeLogDataConnector' - Change log entry ChangeLogEntry[timestamp=2013-10-03 19:10:30.76,sequence=685336,category=group,actionname=updateGroup,
contextId=e9f72d51126645cb88e060d05b35da7f] attribute description : description 14
2013-10-03 19:11:00,078: [DefaultQuartzScheduler_Worker-6] TRACE ChangeLogDataConnector.resolve(114) - - ChangeLog data connector 'UpdateGroupDisplayNameChangeLogDataConnector' - Change log entry ChangeLogEntry[timestamp=2013-10-03 19:10:30.76,sequence=685336,category=group,actionname=updateGroup,
contextId=e9f72d51126645cb88e060d05b35da7f] attribute propertyChanged : displayExtension
2013-10-03 19:11:00,078: [DefaultQuartzScheduler_Worker-6] TRACE ChangeLogDataConnector.resolve(114) - - ChangeLog data connector 'UpdateGroupDisplayNameChangeLogDataConnector' - Change log entry ChangeLogEntry[timestamp=2013-10-03 19:10:30.76,sequence=685336,category=group,actionname=updateGroup,
contextId=e9f72d51126645cb88e060d05b35da7f] attribute propertyOldValue : Deans 8
2013-10-03 19:11:00,078: [DefaultQuartzScheduler_Worker-6] TRACE ChangeLogDataConnector.resolve(114) - - ChangeLog data connector 'UpdateGroupDisplayNameChangeLogDataConnector' - Change log entry ChangeLogEntry[timestamp=2013-10-03 19:10:30.76,sequence=685336,category=group,actionname=updateGroup,
contextId=e9f72d51126645cb88e060d05b35da7f] attribute propertyNewValue : Deans 9
2013-10-03 19:11:00,078: [DefaultQuartzScheduler_Worker-6] TRACE ChangeLogDataConnector.resolve(114) - - ChangeLog data connector 'UpdateGroupDisplayNameChangeLogDataConnector' - Change log entry ChangeLogEntry[timestamp=2013-10-03 19:10:30.76,sequence=685336,category=group,actionname=updateGroup,
contextId=e9f72d51126645cb88e060d05b35da7f] attribute actionName : updateGroup
2013-10-03 19:11:00,078: [DefaultQuartzScheduler_Worker-6] TRACE ChangeLogDataConnector.resolve(114) - - ChangeLog data connector 'UpdateGroupDisplayNameChangeLogDataConnector' - Change log entry ChangeLogEntry[timestamp=2013-10-03 19:10:30.76,sequence=685336,category=group,actionname=updateGroup,
contextId=e9f72d51126645cb88e060d05b35da7f] attribute changeLogCategory : group
2013-10-03 19:11:00,079: [DefaultQuartzScheduler_Worker-6] TRACE ChangeLogDataConnector.resolve(114) - - ChangeLog data connector 'UpdateGroupDisplayNameChangeLogDataConnector' - Change log entry ChangeLogEntry[timestamp=2013-10-03 19:10:30.76,sequence=685336,category=group,actionname=updateGroup,
contextId=e9f72d51126645cb88e060d05b35da7f] attribute sequenceNumber : 685336
2013-10-03 19:11:00,079: [DefaultQuartzScheduler_Worker-6] TRACE ChangeLogDataConnector.resolve(114) - - ChangeLog data connector 'UpdateGroupDisplayNameChangeLogDataConnector' - Change log entry ChangeLogEntry[timestamp=2013-10-03 19:10:30.76,sequence=685336,category=group,actionname=updateGroup,
2013-10-03 19:11:00,079: [DefaultQuartzScheduler_Worker-6] DEBUG FilteredNameAttributeDefinition.doResolve(59) - - Filtered name attribute definition 'groupNameInStem' - Resolve principal 'change_log_sequence_number:685336'
2013-10-03 19:11:00,079: [DefaultQuartzScheduler_Worker-6] TRACE FilteredNameAttributeDefinition.doResolve(73) - - Filtered name attribute definition 'groupNameInStem' - Resolve principal 'change_log_sequence_number:685336' value 'edu:berkeley:app:calmessages:deans'
2013-10-03 19:11:00,079: [DefaultQuartzScheduler_Worker-6] DEBUG LdapDnFromGrouperNamePSOIdentifierAttributeDefinition.doResolve(189) - - Ldap dn from grouper name attribute definition 'groupDn' - Resolve principal 'change_log_sequence_number:685336'
2013-10-03 19:11:00,080: [DefaultQuartzScheduler_Worker-6] TRACE LdapDnFromGrouperNamePSOIdentifierAttributeDefinition.doResolve(264) - - Ldap dn from grouper name attribute definition 'groupDn' - Resolve principal 'change_log_sequence_number:685336' Returned value 'PSOIdentifier[id='cn=edu:berkel
ey:app:calmessages:deans,ou=campus groups,dc=berkeley,dc=edu',targetID=<null>,containerID=<null>]'
2013-10-03 19:11:00,080: [DefaultQuartzScheduler_Worker-6] DEBUG LdapDnFromGrouperNamePSOIdentifierAttributeDefinition.doResolve(189) - - Ldap dn from grouper name attribute definition 'groupDnAlternate' - Resolve principal 'change_log_sequence_number:685336'
2013-10-03 19:11:00,080: [DefaultQuartzScheduler_Worker-6] DEBUG LdapDnFromGrouperNamePSOIdentifierAttributeDefinition.doResolve(197) - - Ldap dn from grouper name attribute definition 'groupDnAlternate' - Resolve principal 'change_log_sequence_number:685336' No dependencies
2013-10-03 19:11:00,080: [DefaultQuartzScheduler_Worker-6] DEBUG LdapDnFromGrouperNamePSOIdentifierAttributeDefinition.doResolve(189) - - Ldap dn from grouper name attribute definition 'groupDnAlternateChangeLog' - Resolve principal 'change_log_sequence_number:685336'
2013-10-03 19:11:00,080: [DefaultQuartzScheduler_Worker-6] DEBUG LdapDnFromGrouperNamePSOIdentifierAttributeDefinition.doResolve(197) - - Ldap dn from grouper name attribute definition 'groupDnAlternateChangeLog' - Resolve principal 'change_log_sequence_number:685336' No dependencies
2013-10-03 19:11:00,080: [DefaultQuartzScheduler_Worker-6] DEBUG ChangeLogDataConnector.resolve(68) - - ChangeLog data connector 'AddMembershipChangeLogDataConnector' - Resolve principal 'change_log_sequence_number:685336'
2013-10-03 19:11:00,081: [DefaultQuartzScheduler_Worker-6] TRACE ChangeLogDataConnector.resolve(69) - - ChangeLog data connector 'AddMembershipChangeLogDataConnector' - Resolve principal 'change_log_sequence_number:685336' requested attributes [stemDn, stemDnAlternateChangeLog, stemObjectclass, st
emOu, stemDescription, groupDn, groupDnAlternate, groupDnAlternateChangeLog, groupObjectclass, cn, groupDescription, groupDisplayName, memberDn, changeLogMembershipGroupDn]
2013-10-03 19:11:00,085: [DefaultQuartzScheduler_Worker-6] DEBUG ChangeLogDataConnector.resolve(98) - - ChangeLog data connector 'AddMembershipChangeLogDataConnector' - Ignoring changelog 'ChangeLogEntry[timestamp=2013-10-03 19:10:30.76,sequence=685336,category=group,actionname=updateGroup,context
Id=e9f72d51126645cb88e060d05b35da7f]'
2013-10-03 19:11:00,085: [DefaultQuartzScheduler_Worker-6] DEBUG ChangeLogDataConnector.resolve(68) - - ChangeLog data connector 'DeleteMembershipChangeLogDataConnector' - Resolve principal 'change_log_sequence_number:685336'
2013-10-03 19:11:00,085: [DefaultQuartzScheduler_Worker-6] TRACE ChangeLogDataConnector.resolve(69) - - ChangeLog data connector 'DeleteMembershipChangeLogDataConnector' - Resolve principal 'change_log_sequence_number:685336' requested attributes [stemDn, stemDnAlternateChangeLog, stemObjectclass,
stemOu, stemDescription, groupDn, groupDnAlternate, groupDnAlternateChangeLog, groupObjectclass, cn, groupDescription, groupDisplayName, memberDn, changeLogMembershipGroupDn]
2013-10-03 19:11:00,090: [DefaultQuartzScheduler_Worker-6] DEBUG ChangeLogDataConnector.resolve(98) - - ChangeLog data connector 'DeleteMembershipChangeLogDataConnector' - Ignoring changelog 'ChangeLogEntry[timestamp=2013-10-03 19:10:30.76,sequence=685336,category=group,actionname=updateGroup,cont
extId=e9f72d51126645cb88e060d05b35da7f]'
2013-10-03 19:11:00,093: [DefaultQuartzScheduler_Worker-6] DEBUG MemberDataConnector$1.callback(82) - - Member data connector 'MemberDataConnector' - Resolve principal 'change_log_sequence_number:685336'
2013-10-03 19:11:00,093: [DefaultQuartzScheduler_Worker-6] TRACE MemberDataConnector$1.callback(83) - - Member data connector 'MemberDataConnector' - Resolve principal 'change_log_sequence_number:685336' requested attributes [stemDn, stemDnAlternateChangeLog, stemObjectclass, stemOu, stemDescripti
on, groupDn, groupDnAlternate, groupDnAlternateChangeLog, groupObjectclass, cn, groupDescription, groupDisplayName, memberDn, changeLogMembershipGroupDn]
2013-10-03 19:11:00,093: [DefaultQuartzScheduler_Worker-6] DEBUG MemberDataConnector$1.callback(87) - - Group data connector 'MemberDataConnector' - Ignoring principal name 'change_log_sequence_number:685336'
2013-10-03 19:11:00,094: [DefaultQuartzScheduler_Worker-6] DEBUG PsoIdentifierAttributeDefinition.doResolve(50) - - PSOIdentifier attribute definition 'memberDn' - Resolve principal 'change_log_sequence_number:685336'
2013-10-03 19:11:00,094: [DefaultQuartzScheduler_Worker-6] DEBUG FilteredNameAttributeDefinition.doResolve(59) - - Filtered name attribute definition 'changeLogMembershipGroupName' - Resolve principal 'change_log_sequence_number:685336'
2013-10-03 19:11:00,094: [DefaultQuartzScheduler_Worker-6] DEBUG LdapDnFromGrouperNamePSOIdentifierAttributeDefinition.doResolve(189) - - Ldap dn from grouper name attribute definition 'changeLogMembershipGroupDn' - Resolve principal 'change_log_sequence_number:685336'
2013-10-03 19:11:00,094: [DefaultQuartzScheduler_Worker-6] DEBUG LdapDnFromGrouperNamePSOIdentifierAttributeDefinition.doResolve(197) - - Ldap dn from grouper name attribute definition 'changeLogMembershipGroupDn' - Resolve principal 'change_log_sequence_number:685336' No dependencies
2013-10-03 19:11:00,094: [DefaultQuartzScheduler_Worker-6] DEBUG SimpleAttributeAuthority.getAttributes(106) - - get attributes 'change_log_sequence_number:685336' aa 'psp.AttributeAuthority' returned 'groupDn' : org.openspml.v2.msg.spml.PSOIdentifier@8bad87d5
2013-10-03 19:11:00,094: [DefaultQuartzScheduler_Worker-6] DEBUG SimpleAttributeAuthority.getAttributes(106) - - get attributes 'change_log_sequence_number:685336' aa 'psp.AttributeAuthority' returned 'stemObjectclass' : top
2013-10-03 19:11:00,095: [DefaultQuartzScheduler_Worker-6] DEBUG SimpleAttributeAuthority.getAttributes(106) - - get attributes 'change_log_sequence_number:685336' aa 'psp.AttributeAuthority' returned 'stemObjectclass' : organizationalUnit
2013-10-03 19:11:00,095: [DefaultQuartzScheduler_Worker-6] DEBUG Psp.execute(1072) - - PSP 'psp' - Calc CalcRequest[id=change_log_sequence_number:685336,requestID=2013/10/03-19:11:00.028,returnData=data] Resolved attributes '[groupDn, stemObjectclass]'.
2013-10-03 19:11:00,095: [DefaultQuartzScheduler_Worker-6] DEBUG Pso.getPSO(222) - - Pso 'stem' - Get pso for 'change_log_sequence_number:685336'
2013-10-03 19:11:00,095: [DefaultQuartzScheduler_Worker-6] DEBUG PsoIdentifier.getPSOIdentifier(86) - - PSO Identifier Definition 'stemDn' - Source attribute 'stemDn' does not exist
2013-10-03 19:11:00,095: [DefaultQuartzScheduler_Worker-6] DEBUG Pso.getPSO(229) - - Pso 'stem' - Unable to calculate pso identifier for 'change_log_sequence_number:685336'
2013-10-03 19:11:00,095: [DefaultQuartzScheduler_Worker-6] DEBUG Pso.getPSO(222) - - Pso 'group' - Get pso for 'change_log_sequence_number:685336'
2013-10-03 19:11:00,095: [DefaultQuartzScheduler_Worker-6] DEBUG PsoIdentifier.getPSOIdentifier(114) - - PSO Identifier Definition 'groupDn' - Returned 'PSOIdentifier[id='cn=edu:berkeley:app:calmessages:deans,ou=campus groups,dc=berkeley,dc=edu',targetID=ldap,containerID=<null>]'
2013-10-03 19:11:00,095: [DefaultQuartzScheduler_Worker-6] DEBUG PsoIdentifier.getPSOIdentifier(86) - - PSO Identifier Definition 'groupDn' - Source attribute 'groupDnAlternate' does not exist
2013-10-03 19:11:00,096: [DefaultQuartzScheduler_Worker-6] DEBUG PsoIdentifier.getPSOIdentifier(86) - - PSO Identifier Definition 'groupDn' - Source attribute 'groupDnAlternateChangeLog' does not exist
2013-10-03 19:11:00,096: [DefaultQuartzScheduler_Worker-6] DEBUG Pso.getPSO(282) - - Pso 'group' - Get pso for 'change_log_sequence_number:685336' returned 1 objects.
2013-10-03 19:11:00,096: [DefaultQuartzScheduler_Worker-6] DEBUG Pso.getPSO(222) - - Pso 'member' - Get pso for 'change_log_sequence_number:685336'
2013-10-03 19:11:00,096: [DefaultQuartzScheduler_Worker-6] DEBUG PsoIdentifier.getPSOIdentifier(86) - - PSO Identifier Definition 'memberDn' - Source attribute 'memberDn' does not exist
2013-10-03 19:11:00,096: [DefaultQuartzScheduler_Worker-6] DEBUG Pso.getPSO(229) - - Pso 'member' - Unable to calculate pso identifier for 'change_log_sequence_number:685336'
2013-10-03 19:11:00,096: [DefaultQuartzScheduler_Worker-6] DEBUG Pso.getPSO(222) - - Pso 'groupMembership' - Get pso for 'change_log_sequence_number:685336'
2013-10-03 19:11:00,096: [DefaultQuartzScheduler_Worker-6] DEBUG PsoIdentifier.getPSOIdentifier(86) - - PSO Identifier Definition 'changeLogMembershipGroupDn' - Source attribute 'changeLogMembershipGroupDn' does not exist
2013-10-03 19:11:00,097: [DefaultQuartzScheduler_Worker-6] DEBUG Pso.getPSO(229) - - Pso 'groupMembership' - Unable to calculate pso identifier for 'change_log_sequence_number:685336'
2013-10-03 19:11:00,097: [DefaultQuartzScheduler_Worker-6] INFO Psp.execute(1009) - - Psp 'psp' - Calc CalcResponse[id=change_log_sequence_number:685336,status=success,requestID=2013/10/03-19:11:00.028,pso=PSO[psoID=PSOIdentifier[id='cn=edu:berkeley:app:calmessages:deans,ou=campus groups,dc=berke
ley,dc=edu',targetID=ldap,containerID=<null>]]]
2013-10-03 19:11:00,097: [DefaultQuartzScheduler_Worker-6] INFO Psp.execute(1011) - - Psp 'psp' - Calc XML:
<psp:id ID='change_log_sequence_number:685336'/>
<psp:pso entityName='group'>
<psoID ID='cn=edu:berkeley:app:calmessages:deans,ou=campus groups,dc=berkeley,dc=edu' targetID='ldap'/>
</psp:pso>
</psp:calcResponse>
On Oct 3, 2013, at 8:59 AM, David Langenberg <> wrote:
Hi Jeff,
Would you please up logging to debug & send the results?
Thanks
Dave