null pointer on loader

 

 

From: Gettes, Michael 
Sent: Tuesday, June 12, 2018 11:55 PM
To: Hyzer, Chris <mchyzer@isc.upenn.edu>
Subject: Re: NPE on loader sync

 

Here is another example: an additional line at the bottom

 

daemon_1    | 2018-06-13T03:48:27.711974600Z java.lang.RuntimeException: Problem with group: Course:201805:CHM7980:all
daemon_1    | 2018-06-13T03:48:27.712016600Z 

at edu.internet2.middleware.grouper.app.loader.GrouperLoaderType.syncOneGroupMembership(GrouperLoaderType.java:3136)
daemon_1    | 2018-06-13T03:48:27.712087300Z 

at edu.internet2.middleware.grouper.app.loader.GrouperLoaderType.syncGroupLogicForOneGroup(GrouperLoaderType.java:1913)
daemon_1    | 2018-06-13T03:48:27.712118900Z 

at edu.internet2.middleware.grouper.app.loader.GrouperLoaderType.access$200(GrouperLoaderType.java:119)
daemon_1    | 2018-06-13T03:48:27.712142600Z 

at edu.internet2.middleware.grouper.app.loader.GrouperLoaderType$12.callLogic(GrouperLoaderType.java:1819)
daemon_1    | 2018-06-13T03:48:27.712172600Z 

at edu.internet2.middleware.grouper.app.loader.GrouperLoaderType$12.callLogic(GrouperLoaderType.java:1809)
daemon_1    | 2018-06-13T03:48:27.712194800Z 

at edu.internet2.middleware.grouper.util.GrouperCallable$1.callback(GrouperCallable.java:191)
daemon_1    | 2018-06-13T03:48:27.712229200Z 

at edu.internet2.middleware.grouper.GrouperSession.callbackGrouperSession(GrouperSession.java:974)
daemon_1    | 2018-06-13T03:48:27.712251400Z 

at edu.internet2.middleware.grouper.util.GrouperCallable.callLogicWithSessionIfExists(GrouperCallable.java:188)
daemon_1    | 2018-06-13T03:48:27.712271700Z 

at edu.internet2.middleware.grouper.util.GrouperCallable.call(GrouperCallable.java:154)
daemon_1    | 2018-06-13T03:48:27.712292800Z 

at java.util.concurrent.FutureTask.run(FutureTask.java:266)
daemon_1    | 2018-06-13T03:48:27.712313800Z 

at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
daemon_1    | 2018-06-13T03:48:27.712334100Z 

at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
daemon_1    | 2018-06-13T03:48:27.712358300Z 

at java.lang.Thread.run(Thread.java:748)
daemon_1    | 2018-06-13T03:48:27.712391400Z Caused by: java.lang.NullPointerException
daemon_1    | 2018-06-13T03:48:27.712426800Z 

at edu.internet2.middleware.grouper.app.loader.GrouperLoaderType.syncOneGroupMembership(GrouperLoaderType.java:2787)
daemon_1    | 2018-06-13T03:48:27.712455500Z 

... 12 more

 

On Jun 12, 2018, at 11:08 AM, Hyzer, Chris <mchyzer@isc.upenn.edu> wrote:

 

Can you please send more logs?  There should be more stacks or more to the stack…

 

From: grouper-users-request@internet2.edu [grouper-users-request@internet2.edu] On Behalf Of ** Gettes, Michael
Sent: Monday, June 11, 2018 7:14 PM
To: grouper-users@internet2.edu
Subject: [grouper-users] NPE on loader sync

 

I have a loader job which seems to get through handling the memberships and then I get many NPEs on the job rather consistently but I can’t tell why.  I have looked at the code but what is running in the TIER package seems different from the current code and I am not sure which branch lines up with what is running. 

 

I have looked at what I am producing in the loader very carefully and the output really does appear to be good.

 

If I could get a better hint as to what the NPE really represents as to what I may be doing wrong with my output… I’d appreciate knowing.

 

I am running what built at https://jenkins.testbed.tier.internet2.edu/job/docker/job/grouper/job/master/113/

 

I am seeing many of these NPE(s), one for each group having members:

 

^{[[36;1mdaemon_1    |}[[0m 2018-06-11T17:36:19.519131000Z grouper-api;grouper_error.log;dev;build-2;2018-06-11 17:36:19,518: [pool-1-thread-512] ERROR GrouperLoaderType.syncGroupLogicForOneGroup(1923)    Error in job for group: Course:201805:CHM7980:7407
^{[[36;1mdaemon_1    |}[[0m 2018-06-11T17:36:19.519188400Z java.lang.RuntimeException: Problem with group: Course:201805:CHM7980:7407
^{[[36;1mdaemon_1    |}[[0m 2018-06-11T17:36:19.519204600Z       at edu.internet2.middleware.grouper.app.loader.GrouperLoaderType.syncOneGroupMembership(GrouperLoaderType.java:3136)
^{[[36;1mdaemon_1    |}[[0m 2018-06-11T17:36:19.519219800Z       at edu.internet2.middleware.grouper.app.loader.GrouperLoaderType.syncGroupLogicForOneGroup(GrouperLoaderType.java:1913)
^{[[36;1mdaemon_1    |}[[0m 2018-06-11T17:36:19.519234400Z       at edu.internet2.middleware.grouper.app.loader.GrouperLoaderType.access$200(GrouperLoaderType.java:119)
^{[[36;1mdaemon_1    |}[[0m 2018-06-11T17:36:19.519263400Z       at edu.internet2.middleware.grouper.app.loader.GrouperLoaderType$12.callLogic(GrouperLoaderType.java:1819)
^{[[36;1mdaemon_1    |}[[0m 2018-06-11T17:36:19.519278400Z       at edu.internet2.middleware.grouper.app.loader.GrouperLoaderType$12.callLogic(GrouperLoaderType.java:1809)
^{[[36;1mdaemon_1    |}[[0m 2018-06-11T17:36:19.519292600Z       at edu.internet2.middleware.grouper.util.GrouperCallable$1.callback(GrouperCallable.java:191)
^{[[36;1mdaemon_1    |}[[0m 2018-06-11T17:36:19.519308300Z       at edu.internet2.middleware.grouper.GrouperSession.callbackGrouperSession(GrouperSession.java:974)
^{[[36;1mdaemon_1    |}[[0m 2018-06-11T17:36:19.519323600Z       at edu.internet2.middleware.grouper.util.GrouperCallable.callLogicWithSessionIfExists(GrouperCallable.java:188)
^{[[36;1mdaemon_1    |}[[0m 2018-06-11T17:36:19.519338700Z       at edu.internet2.middleware.grouper.util.GrouperCallable.call(GrouperCallable.java:154)
^{[[36;1mdaemon_1    |}[[0m 2018-06-11T17:36:19.519352800Z       at java.util.concurrent.FutureTask.run(FutureTask.java:266)
^{[[36;1mdaemon_1    |}[[0m 2018-06-11T17:36:19.519367600Z       at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
^{[[36;1mdaemon_1    |}[[0m 2018-06-11T17:36:19.519382800Z       at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
^{[[36;1mdaemon_1    |}[[0m 2018-06-11T17:36:19.519396600Z       at java.lang.Thread.run(Thread.java:748)
^{[[36;1mdaemon_1    |}[[0m 2018-06-11T17:36:19.519409000Z Caused by: java.lang.NullPointerException