Grouper Users - Open Discussion List

["Peter St. Onge" <>]

I've been experimenting with Grouper locally and have become somewhat
stymied with provisioning.

I'm using a local LDAP as my subject source; it's also where I want
Grouper to  provision groups to. I can search for any of my attributes
of interest from the command line, as well as in the web UI, and get
back the results I expect to see...

gsh 0% GrouperSession.startRootSession()
edu.internet2.middleware.grouper.GrouperSession:
4ee55c98f5794f08891734319cf30a8d,'GrouperSystem','application'
gsh 1% subj = SubjectFinder.findByIdOrIdentifier("hermesal", false)
subject: id='100000168' type='person' source='ldap' name='Alice Hermes'
[Not a real person; the users in this LDAP were generated]

The Grouper loader is able to write new folders into LDAP without
problem, as well with the empty groups, although I do find this in the
API's grouper_error.log:

2014-04-01 14:36:13,572: [DefaultQuartzScheduler_Worker-1] WARN
Psp.getAllSourceIdentifiers(1584) -  - PSP 'psp' - Unable to resolve
attribute 'groupNames'
2014-04-01 14:36:13,572: [DefaultQuartzScheduler_Worker-1] WARN
Psp.getAllSourceIdentifiers(1584) -  - PSP 'psp' - Unable to resolve
attribute 'memberSubjectIds'


When I try to provision users into groups, the resulting group looks
like this:

dn: cn=tg1,ou=fooroot,ou=grouper,dc=fqdn
objectClass: eduMember
objectClass: groupOfNames
objectClass: top
description: Test Group 1
member:
cn: tg1
structuralObjectClass: groupOfNames
hasMember: Alice Deeds

A peculiarity a couple of us here have noticed is that when stored as
'groupOfNames' the entries seem to disappear after a short amount of
time...

Some caveats - using the above result, our dn's are of this form:

dn: fooid=100000168,dc=fqdn

The fooid is defined in the institutional LDAP schema, and is used
internally. 'fooid' seems to be mapping correctly to the 'id'

When I restart the grouper loader, I see lines like this in
grouper_error.log when it tries to do the full update:


2014-04-01 15:06:02,947: [DefaultQuartzScheduler_Worker-1] INFO
Psp.execute(980) -  - Psp 'psp' - Calc
CalcRequest[id=100000042,requestID=2014/04/01-15:06:02.947,returnData=everything,schemaEntityRef=SchemaEntityRef[targetID=ldap,entityName=member,isContainer=false]]
2014-04-01 15:06:02,948: [DefaultQuartzScheduler_Worker-1] INFO
Psp.execute(984) -  - Psp 'psp' - Calc XML:
<psp:calcRequest xmlns:psp='http://grouper.internet2.edu/psp'
requestID='2014/04/01-15:06:02.947' returnData='everything'>
  <psp:id ID='100000042'/>
  <psp:schemaEntity targetID='ldap' entityName='member'/>
</psp:calcRequest>

2014-04-01 15:06:02,976: [DefaultQuartzScheduler_Worker-1] ERROR
Psp.execute(1015) -  - Psp 'psp' - Calc
CalcResponse[id=100000042,status=failure,error=noSuchIdentifier,errorMessages={Unable
to calculate provisioned object.},requestID=2014/04/01-15:06:02.947]
2014-04-01 15:06:02,984: [DefaultQuartzScheduler_Worker-1] ERROR
Psp.execute(1017) -  - Psp 'psp' - Calc XML:
<psp:calcResponse xmlns:psp='http://grouper.internet2.edu/psp'
status='failure' requestID='2014/04/01-15:06:02.947'
error='noSuchIdentifier'>
  <errorMessage>Unable to calculate provisioned object.</errorMessage>
  <psp:id ID='100000042'/>
</psp:calcResponse>

2014-04-01 15:06:02,986: [DefaultQuartzScheduler_Worker-1] ERROR
Psp.execute(1175) -  - Psp 'psp' - Diff
DiffResponse[id=100000042,status=failure,error=noSuchIdentifier,errorMessages={Unable
to calculate provisioned object.},requestID=2014/04/01-15:06:02.937]
2014-04-01 15:06:02,996: [DefaultQuartzScheduler_Worker-1] ERROR
Psp.execute(1177) -  - Psp 'psp' - Diff XML:
<psp:diffResponse xmlns:psp='http://grouper.internet2.edu/psp'
status='failure' requestID='2014/04/01-15:06:02.937'
error='noSuchIdentifier'>
  <errorMessage>Unable to calculate provisioned object.</errorMessage>
  <psp:id ID='100000042'/>
</psp:diffResponse>

2014-04-01 15:06:02,996: [DefaultQuartzScheduler_Worker-1] ERROR
Psp.execute(1440) -  - Psp 'psp' - Sync
SyncResponse[id=100000042,status=failure,error=noSuchIdentifier,errorMessages={Unable
to calculate provisioned object.},requestID=2014/04/01-15:06:02.935]
2014-04-01 15:06:02,997: [DefaultQuartzScheduler_Worker-1] ERROR
Psp.execute(1442) -  - Psp 'psp' - Sync SPML:
<psp:syncResponse xmlns:psp='http://grouper.internet2.edu/psp'
status='failure' requestID='2014/04/01-15:06:02.935'
error='noSuchIdentifier'>
  <errorMessage>Unable to calculate provisioned object.</errorMessage>
  <psp:id ID='100000042'/>
</psp:syncResponse>

2014-04-01 15:06:03,000: [DefaultQuartzScheduler_Worker-1] ERROR
Psp.execute(811) -  - Psp 'psp' - BulkSync
BulkSyncResponse[id=<null>,status=failure,error=<null>,errorMessages={},requestID=2014/04/01-15:06:01.574,responses=2]
2014-04-01 15:06:03,001: [DefaultQuartzScheduler_Worker-1] ERROR
Psp.execute(813) -  - Psp 'psp' -  BulkSync SPML:
<psp:bulkSyncResponse xmlns:psp='http://grouper.internet2.edu/psp'
status='failure' requestID='2014/04/01-15:06:01.574'>
  <psp:syncResponse status='success' requestID='2014/04/01-15:06:01.845'>
    <psp:synchronizedResponse xmlns:psp='http://grouper.internet2.edu/psp'>
      <psp:psoID ID='ou=test,ou=fooroot,ou=grouper,dc=fqdn'
targetID='ldap'/>
    </psp:synchronizedResponse>
    <psp:id ID='fooroot:test'/>
  </psp:syncResponse>
  <psp:syncResponse status='success' requestID='2014/04/01-15:06:02.131'>
    <psp:synchronizedResponse xmlns:psp='http://grouper.internet2.edu/psp'>
      <psp:psoID ID='cn=tg1,ou=fooroot,ou=grouper,dc=fqfdn'
targetID='ldap'/>
    </psp:synchronizedResponse>
    <psp:id ID='fooroot:tg1'/>
  </psp:syncResponse>
</psp:bulkSyncResponse>

2014-04-01 15:06:03,005: [DefaultQuartzScheduler_Worker-1] ERROR
PspChangeLogConsumer.fullSync(344) -  - PSP Consumer 'null' - Full sync
was not successful
'BulkSyncResponse[id=<null>,status=failure,error=<null>,errorMessages={},requestID=2014/04/01-15:06:01.574,responses=2]'

Any and all questions/pointers appreciated.

Thanks and best, -- pete

-- 
Peter St. Onge