Skip to Content.
Sympa Menu

grouper-dev - [grouper-dev] PSPNG looping on error

Subject: Grouper Developers Forum

List archive

[grouper-dev] PSPNG looping on error


Chronological Thread 
    < Chronological >      < Thread >
  • From: "Gettes, Michael" <>
  • To: "" <>
  • Subject: [grouper-dev] PSPNG looping on error
  • Date: Thu, 7 Feb 2019 04:12:11 +0000
tier/grouper:2.4.0-a23-u9-w2-p2-20190206-rc1

Nothing I do gets me to skip over the changes… I will have to go into the
changelog consumer table and skip around the offender. Here’s the log.

I hope this helps.

daemon_1 | 2019-02-07T04:09:10.733057300Z
grouper-api;grouper_error.log;dev;daemon;2019-02-06 23:09:10,732:
[DefaultQuartzScheduler_Worker-5] WARN LdapSystem.performLdapAdd(344) - -
UFdemo: Skipping LDAP ADD because object already existed: cn=Test:a test
group,ou=groups,dc=internet2,dc=edu
daemon_1 | 2019-02-07T04:09:10.888219600Z
grouper-api;grouper_error.log;dev;daemon;2019-02-06 23:09:10,887:
[DefaultQuartzScheduler_Worker-3] WARN LdapSystem.performLdapAdd(344) - -
UFdemo: Skipping LDAP ADD because object already existed: cn=Test:a test
group,ou=groups,dc=internet2,dc=edu
daemon_1 | 2019-02-07T04:09:11.484796500Z
grouper-api;grouper_error.log;dev;daemon;2019-02-06 23:09:11,484:
[DefaultQuartzScheduler_Worker-5] WARN
LdapProvisioner.makeCoalescedLdapChanges(423) - - Found 1 conflicting ldap
operations in event batch. Scheduling a full sync on affected groups
daemon_1 | 2019-02-07T04:09:11.502410300Z
grouper-api;grouper_error.log;dev;daemon;2019-02-06 23:09:11,501:
[DefaultQuartzScheduler_Worker-5] ERROR ChangeLogHelper.processRecords(255) -
- Error
daemon_1 | 2019-02-07T04:09:11.502486500Z java.lang.NullPointerException
daemon_1 | 2019-02-07T04:09:11.502591700Z at
edu.internet2.middleware.grouper.pspng.LdapProvisioner.isWorkItemMakingChange(LdapProvisioner.java:511)
daemon_1 | 2019-02-07T04:09:11.502732900Z at
edu.internet2.middleware.grouper.pspng.LdapProvisioner.makeCoalescedLdapChanges(LdapProvisioner.java:431)
daemon_1 | 2019-02-07T04:09:11.502877900Z at
edu.internet2.middleware.grouper.pspng.LdapProvisioner.finishProvisioningBatch(LdapProvisioner.java:281)
daemon_1 | 2019-02-07T04:09:11.502934600Z at
edu.internet2.middleware.grouper.pspng.Provisioner.provisionBatchOfItems(Provisioner.java:1687)
daemon_1 | 2019-02-07T04:09:11.503002700Z at
edu.internet2.middleware.grouper.pspng.PspChangelogConsumerShim.processChangeLogEntries(PspChangelogConsumerShim.java:71)
daemon_1 | 2019-02-07T04:09:11.503032200Z at
edu.internet2.middleware.grouper.changeLog.ChangeLogHelper.processRecords(ChangeLogHelper.java:245)
daemon_1 | 2019-02-07T04:09:11.503060900Z at
edu.internet2.middleware.grouper.app.loader.GrouperLoaderType$5.runJob(GrouperLoaderType.java:638)
daemon_1 | 2019-02-07T04:09:11.503093200Z at
edu.internet2.middleware.grouper.app.loader.GrouperLoaderJob.runJob(GrouperLoaderJob.java:465)
daemon_1 | 2019-02-07T04:09:11.503159900Z at
edu.internet2.middleware.grouper.app.loader.GrouperLoaderJob.execute(GrouperLoaderJob.java:345)
daemon_1 | 2019-02-07T04:09:11.503188800Z at
org.quartz.core.JobRunShell.run(JobRunShell.java:202)
daemon_1 | 2019-02-07T04:09:11.503217600Z at
org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:573)
daemon_1 | 2019-02-07T04:09:11.506481000Z
grouper-api;grouper_error.log;dev;daemon;2019-02-06 23:09:11,505:
[DefaultQuartzScheduler_Worker-5] ERROR ChangeLogHelper.processRecords(286) -
- Did not get all the way through the batch! -1 != 2166729
daemon_1 | 2019-02-07T04:09:11.508553600Z
grouper-api;grouper_error.log;dev;daemon;2019-02-06 23:09:11,507:
[DefaultQuartzScheduler_Worker-5] ERROR GrouperLoaderJob.runJob(485) - -
Error on job: CHANGE_LOG_consumer_psp_posixGroup
daemon_1 | 2019-02-07T04:09:11.508679100Z java.lang.RuntimeException:
Error in loader job: null, check logs: Error: java.lang.NullPointerException
daemon_1 | 2019-02-07T04:09:11.508716200Z at
edu.internet2.middleware.grouper.pspng.LdapProvisioner.isWorkItemMakingChange(LdapProvisioner.java:511)
daemon_1 | 2019-02-07T04:09:11.508746000Z at
edu.internet2.middleware.grouper.pspng.LdapProvisioner.makeCoalescedLdapChanges(LdapProvisioner.java:431)
daemon_1 | 2019-02-07T04:09:11.508778600Z at
edu.internet2.middleware.grouper.pspng.LdapProvisioner.finishProvisioningBatch(LdapProvisioner.java:281)
daemon_1 | 2019-02-07T04:09:11.508812500Z at
edu.internet2.middleware.grouper.pspng.Provisioner.provisionBatchOfItems(Provisioner.java:1687)
daemon_1 | 2019-02-07T04:09:11.508845900Z at
edu.internet2.middleware.grouper.pspng.PspChangelogConsumerShim.processChangeLogEntries(PspChangelogConsumerShim.java:71)
daemon_1 | 2019-02-07T04:09:11.508880700Z at
edu.internet2.middleware.grouper.changeLog.ChangeLogHelper.processRecords(ChangeLogHelper.java:245)
daemon_1 | 2019-02-07T04:09:11.508914000Z at
edu.internet2.middleware.grouper.app.loader.GrouperLoaderType$5.runJob(GrouperLoaderType.java:638)
daemon_1 | 2019-02-07T04:09:11.508946900Z at
edu.internet2.middleware.grouper.app.loader.GrouperLoaderJob.runJob(GrouperLoaderJob.java:465)
daemon_1 | 2019-02-07T04:09:11.508980200Z at
edu.internet2.middleware.grouper.app.loader.GrouperLoaderJob.execute(GrouperLoaderJob.java:345)
daemon_1 | 2019-02-07T04:09:11.509008000Z at
org.quartz.core.JobRunShell.run(JobRunShell.java:202)
daemon_1 | 2019-02-07T04:09:11.509035500Z at
org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:573)
daemon_1 | 2019-02-07T04:09:11.509062900Z Did not get all the way through
the batch! -1 != 2166729
daemon_1 | 2019-02-07T04:09:11.509132200Z at
edu.internet2.middleware.grouper.app.loader.GrouperLoaderJob.runJob(GrouperLoaderJob.java:474)
daemon_1 | 2019-02-07T04:09:11.509160400Z at
edu.internet2.middleware.grouper.app.loader.GrouperLoaderJob.execute(GrouperLoaderJob.java:345)
daemon_1 | 2019-02-07T04:09:11.509187900Z at
org.quartz.core.JobRunShell.run(JobRunShell.java:202)
daemon_1 | 2019-02-07T04:09:11.509215600Z at
org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:573)
daemon_1 | 2019-02-07T04:09:11.526823900Z
grouper-api;grouper_error.log;dev;daemon;2019-02-06 23:09:11,526:
[DefaultQuartzScheduler_Worker-5] ERROR GrouperLoaderJob.execute(348) - -
Error running up job
daemon_1 | 2019-02-07T04:09:11.526967300Z java.lang.RuntimeException:
Error in loader job: null, check logs: Error: java.lang.NullPointerException
daemon_1 | 2019-02-07T04:09:11.526999600Z at
edu.internet2.middleware.grouper.pspng.LdapProvisioner.isWorkItemMakingChange(LdapProvisioner.java:511)
daemon_1 | 2019-02-07T04:09:11.527029700Z at
edu.internet2.middleware.grouper.pspng.LdapProvisioner.makeCoalescedLdapChanges(LdapProvisioner.java:431)
daemon_1 | 2019-02-07T04:09:11.527058500Z at
edu.internet2.middleware.grouper.pspng.LdapProvisioner.finishProvisioningBatch(LdapProvisioner.java:281)
daemon_1 | 2019-02-07T04:09:11.527087300Z at
edu.internet2.middleware.grouper.pspng.Provisioner.provisionBatchOfItems(Provisioner.java:1687)
daemon_1 | 2019-02-07T04:09:11.527144900Z at
edu.internet2.middleware.grouper.pspng.PspChangelogConsumerShim.processChangeLogEntries(PspChangelogConsumerShim.java:71)
daemon_1 | 2019-02-07T04:09:11.527185000Z at
edu.internet2.middleware.grouper.changeLog.ChangeLogHelper.processRecords(ChangeLogHelper.java:245)
daemon_1 | 2019-02-07T04:09:11.527215500Z at
edu.internet2.middleware.grouper.app.loader.GrouperLoaderType$5.runJob(GrouperLoaderType.java:638)
daemon_1 | 2019-02-07T04:09:11.527277200Z at
edu.internet2.middleware.grouper.app.loader.GrouperLoaderJob.runJob(GrouperLoaderJob.java:465)
daemon_1 | 2019-02-07T04:09:11.527315200Z at
edu.internet2.middleware.grouper.app.loader.GrouperLoaderJob.execute(GrouperLoaderJob.java:345)
daemon_1 | 2019-02-07T04:09:11.527350500Z at
org.quartz.core.JobRunShell.run(JobRunShell.java:202)
daemon_1 | 2019-02-07T04:09:11.527419500Z at
org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:573)
daemon_1 | 2019-02-07T04:09:11.527454900Z Did not get all the way through
the batch! -1 != 2166729
daemon_1 | 2019-02-07T04:09:11.527488200Z at
edu.internet2.middleware.grouper.app.loader.GrouperLoaderJob.runJob(GrouperLoaderJob.java:474)
daemon_1 | 2019-02-07T04:09:11.527546800Z at
edu.internet2.middleware.grouper.app.loader.GrouperLoaderJob.execute(GrouperLoaderJob.java:345)
daemon_1 | 2019-02-07T04:09:11.527580100Z at
org.quartz.core.JobRunShell.run(JobRunShell.java:202)
daemon_1 | 2019-02-07T04:09:11.527614700Z at
org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:573)
daemon_1 | 2019-02-07T04:09:11.805561600Z
grouper-api;grouper_error.log;dev;daemon;2019-02-06 23:09:11,805:
[DefaultQuartzScheduler_Worker-3] WARN
LdapProvisioner.makeCoalescedLdapChanges(423) - - Found 1 conflicting ldap
operations in event batch. Scheduling a full sync on affected groups
daemon_1 | 2019-02-07T04:09:11.818706200Z
grouper-api;grouper_error.log;dev;daemon;2019-02-06 23:09:11,817:
[DefaultQuartzScheduler_Worker-3] ERROR ChangeLogHelper.processRecords(255) -
- Error
daemon_1 | 2019-02-07T04:09:11.818767300Z java.lang.NullPointerException
daemon_1 | 2019-02-07T04:09:11.818798900Z at
edu.internet2.middleware.grouper.pspng.LdapProvisioner.isWorkItemMakingChange(LdapProvisioner.java:511)
daemon_1 | 2019-02-07T04:09:11.818837400Z at
edu.internet2.middleware.grouper.pspng.LdapProvisioner.makeCoalescedLdapChanges(LdapProvisioner.java:431)
daemon_1 | 2019-02-07T04:09:11.818866700Z at
edu.internet2.middleware.grouper.pspng.LdapProvisioner.finishProvisioningBatch(LdapProvisioner.java:281)
daemon_1 | 2019-02-07T04:09:11.818889100Z at
edu.internet2.middleware.grouper.pspng.Provisioner.provisionBatchOfItems(Provisioner.java:1687)
daemon_1 | 2019-02-07T04:09:11.818915300Z at
edu.internet2.middleware.grouper.pspng.PspChangelogConsumerShim.processChangeLogEntries(PspChangelogConsumerShim.java:71)
daemon_1 | 2019-02-07T04:09:11.818935400Z at
edu.internet2.middleware.grouper.changeLog.ChangeLogHelper.processRecords(ChangeLogHelper.java:245)
daemon_1 | 2019-02-07T04:09:11.818955400Z at
edu.internet2.middleware.grouper.app.loader.GrouperLoaderType$5.runJob(GrouperLoaderType.java:638)
daemon_1 | 2019-02-07T04:09:11.818986700Z at
edu.internet2.middleware.grouper.app.loader.GrouperLoaderJob.runJob(GrouperLoaderJob.java:465)
daemon_1 | 2019-02-07T04:09:11.819015400Z at
edu.internet2.middleware.grouper.app.loader.GrouperLoaderJob.execute(GrouperLoaderJob.java:345)
daemon_1 | 2019-02-07T04:09:11.819055500Z at
org.quartz.core.JobRunShell.run(JobRunShell.java:202)
daemon_1 | 2019-02-07T04:09:11.819084200Z at
org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:573)
daemon_1 | 2019-02-07T04:09:11.831503000Z
grouper-api;grouper_error.log;dev;daemon;2019-02-06 23:09:11,818:
[DefaultQuartzScheduler_Worker-3] ERROR ChangeLogHelper.processRecords(286) -
- Did not get all the way through the batch! -1 != 2166729
daemon_1 | 2019-02-07T04:09:11.833623800Z
grouper-api;grouper_error.log;dev;daemon;2019-02-06 23:09:11,831:
[DefaultQuartzScheduler_Worker-3] ERROR GrouperLoaderJob.runJob(485) - -
Error on job: CHANGE_LOG_consumer_psp_groupOfNames
daemon_1 | 2019-02-07T04:09:11.833678000Z java.lang.RuntimeException:
Error in loader job: null, check logs: Error: java.lang.NullPointerException
daemon_1 | 2019-02-07T04:09:11.833711000Z at
edu.internet2.middleware.grouper.pspng.LdapProvisioner.isWorkItemMakingChange(LdapProvisioner.java:511)
daemon_1 | 2019-02-07T04:09:11.833753200Z at
edu.internet2.middleware.grouper.pspng.LdapProvisioner.makeCoalescedLdapChanges(LdapProvisioner.java:431)
daemon_1 | 2019-02-07T04:09:11.833785400Z at
edu.internet2.middleware.grouper.pspng.LdapProvisioner.finishProvisioningBatch(LdapProvisioner.java:281)
daemon_1 | 2019-02-07T04:09:11.833817700Z at
edu.internet2.middleware.grouper.pspng.Provisioner.provisionBatchOfItems(Provisioner.java:1687)
daemon_1 | 2019-02-07T04:09:11.833847900Z at
edu.internet2.middleware.grouper.pspng.PspChangelogConsumerShim.processChangeLogEntries(PspChangelogConsumerShim.java:71)
daemon_1 | 2019-02-07T04:09:11.833879200Z at
edu.internet2.middleware.grouper.changeLog.ChangeLogHelper.processRecords(ChangeLogHelper.java:245)
daemon_1 | 2019-02-07T04:09:11.833911100Z at
edu.internet2.middleware.grouper.app.loader.GrouperLoaderType$5.runJob(GrouperLoaderType.java:638)
daemon_1 | 2019-02-07T04:09:11.833969000Z at
edu.internet2.middleware.grouper.app.loader.GrouperLoaderJob.runJob(GrouperLoaderJob.java:465)
daemon_1 | 2019-02-07T04:09:11.834000700Z at
edu.internet2.middleware.grouper.app.loader.GrouperLoaderJob.execute(GrouperLoaderJob.java:345)
daemon_1 | 2019-02-07T04:09:11.834030800Z at
org.quartz.core.JobRunShell.run(JobRunShell.java:202)
daemon_1 | 2019-02-07T04:09:11.834062300Z at
org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:573)
daemon_1 | 2019-02-07T04:09:11.834092600Z Did not get all the way through
the batch! -1 != 2166729
daemon_1 | 2019-02-07T04:09:11.834116400Z at
edu.internet2.middleware.grouper.app.loader.GrouperLoaderJob.runJob(GrouperLoaderJob.java:474)
daemon_1 | 2019-02-07T04:09:11.834147800Z at
edu.internet2.middleware.grouper.app.loader.GrouperLoaderJob.execute(GrouperLoaderJob.java:345)
daemon_1 | 2019-02-07T04:09:11.834179300Z at
org.quartz.core.JobRunShell.run(JobRunShell.java:202)
daemon_1 | 2019-02-07T04:09:11.834209600Z at
org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:573)
daemon_1 | 2019-02-07T04:09:11.846824800Z
grouper-api;grouper_error.log;dev;daemon;2019-02-06 23:09:11,846:
[DefaultQuartzScheduler_Worker-3] ERROR GrouperLoaderJob.execute(348) - -
Error running up job
daemon_1 | 2019-02-07T04:09:11.846992900Z java.lang.RuntimeException:
Error in loader job: null, check logs: Error: java.lang.NullPointerException
daemon_1 | 2019-02-07T04:09:11.847028600Z at
edu.internet2.middleware.grouper.pspng.LdapProvisioner.isWorkItemMakingChange(LdapProvisioner.java:511)
daemon_1 | 2019-02-07T04:09:11.847062800Z at
edu.internet2.middleware.grouper.pspng.LdapProvisioner.makeCoalescedLdapChanges(LdapProvisioner.java:431)
daemon_1 | 2019-02-07T04:09:11.847097600Z at
edu.internet2.middleware.grouper.pspng.LdapProvisioner.finishProvisioningBatch(LdapProvisioner.java:281)
daemon_1 | 2019-02-07T04:09:11.847131500Z at
edu.internet2.middleware.grouper.pspng.Provisioner.provisionBatchOfItems(Provisioner.java:1687)
daemon_1 | 2019-02-07T04:09:11.847164900Z at
edu.internet2.middleware.grouper.pspng.PspChangelogConsumerShim.processChangeLogEntries(PspChangelogConsumerShim.java:71)
daemon_1 | 2019-02-07T04:09:11.847199300Z at
edu.internet2.middleware.grouper.changeLog.ChangeLogHelper.processRecords(ChangeLogHelper.java:245)
daemon_1 | 2019-02-07T04:09:11.847233100Z at
edu.internet2.middleware.grouper.app.loader.GrouperLoaderType$5.runJob(GrouperLoaderType.java:638)
daemon_1 | 2019-02-07T04:09:11.847295900Z at
edu.internet2.middleware.grouper.app.loader.GrouperLoaderJob.runJob(GrouperLoaderJob.java:465)
daemon_1 | 2019-02-07T04:09:11.847381600Z at
edu.internet2.middleware.grouper.app.loader.GrouperLoaderJob.execute(GrouperLoaderJob.java:345)
daemon_1 | 2019-02-07T04:09:11.847418100Z at
org.quartz.core.JobRunShell.run(JobRunShell.java:202)
daemon_1 | 2019-02-07T04:09:11.847454300Z at
org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:573)
daemon_1 | 2019-02-07T04:09:11.847563800Z Did not get all the way through
the batch! -1 != 2166729
daemon_1 | 2019-02-07T04:09:11.847599800Z at
edu.internet2.middleware.grouper.app.loader.GrouperLoaderJob.runJob(GrouperLoaderJob.java:474)
daemon_1 | 2019-02-07T04:09:11.847675600Z at
edu.internet2.middleware.grouper.app.loader.GrouperLoaderJob.execute(GrouperLoaderJob.java:345)
daemon_1 | 2019-02-07T04:09:11.847728100Z at
org.quartz.core.JobRunShell.run(JobRunShell.java:202)
daemon_1 | 2019-02-07T04:09:11.847762400Z at
org.quartz.simpl.SimpleThreadPool$WorkerThread.run(SimpleThreadPool.java:573)


  • [grouper-dev] PSPNG looping on error, Gettes, Michael, 02/07/2019

Archive powered by MHonArc 2.6.19.

Top of Page