IdStory Identity Manager

There is some strange behavior with this LRT and commit before transaction. But this issues was explored by old version.

The AddNewAutomaticRoleTaskExecutor LRT was started and executed before IdmRoleTreeNodeDto was saved. There is screens:

Record in all LRT agenda

LRT detail

Audit detail of IdmRoleTreeNodeDto

Error:

eu.bcvsolutions.idm.core.api.exception.ResultCodeException: Automatic role id is required.
    at eu.bcvsolutions.idm.core.scheduler.task.impl.AddNewAutomaticRoleTaskExecutor.getRoleTreeNode(AddNewAutomaticRoleTaskExecutor.java:160)
    at eu.bcvsolutions.idm.core.scheduler.task.impl.AddNewAutomaticRoleTaskExecutor.getItemsToProcess(AddNewAutomaticRoleTaskExecutor.java:74)
    at eu.bcvsolutions.idm.core.scheduler.api.service.AbstractSchedulableStatefulExecutor.executeProcess(AbstractSchedulableStatefulExecutor.java:126)
    at eu.bcvsolutions.idm.core.scheduler.api.service.AbstractSchedulableStatefulExecutor.process(AbstractSchedulableStatefulExecutor.java:58)
    at eu.bcvsolutions.idm.core.scheduler.api.service.AbstractSchedulableStatefulExecutor.process(AbstractSchedulableStatefulExecutor.java:46)
    at eu.bcvsolutions.idm.core.scheduler.api.service.AbstractLongRunningTaskExecutor.call(AbstractLongRunningTaskExecutor.java:176)
    at java.util.concurrent.FutureTask.run(FutureTask.java:266)
    at org.springframework.security.concurrent.DelegatingSecurityContextRunnable.run(DelegatingSecurityContextRunnable.java:80)
    at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
    at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
    at java.lang.Thread.run(Thread.java:748)

Complete log

2019-03-30 13:55:15.588  INFO 10665 --- [nio-8080-exec-1] e.b.i.c.m.s.i.DefaultEntityEventManager  : Publishing event [AutomaticRoleRequestEvent [type: EXECUTE, content: eu.bcvsolutions.idm.core.api.dto.IdmAutomaticRoleRequestDto [code= c224d890-deef-4a13-ab74-2b3fa3028e83], properties: {checkRight=true}]]
2019-03-30 13:55:15.588  INFO 10665 --- [nio-8080-exec-1] e.b.i.c.a.e.AbstractEntityEventProcessor : Processor [automatic-role-request-approval-processor] start for [AutomaticRoleRequestEvent [type: EXECUTE, content: eu.bcvsolutions.idm.core.api.dto.IdmAutomaticRoleRequestDto [code= c224d890-deef-4a13-ab74-2b3fa3028e83], properties: {checkRight=true}]] with order [-1000].
2019-03-30 13:55:15.589  INFO 10665 --- [nio-8080-exec-1] .r.AutomaticRoleRequestApprovalProcessor : None approval process definition was found, request [eu.bcvsolutions.idm.core.api.dto.IdmAutomaticRoleRequestDto [code= c224d890-deef-4a13-ab74-2b3fa3028e83]] for automatic role is approved.
2019-03-30 13:55:15.589  INFO 10665 --- [nio-8080-exec-1] e.b.i.c.a.e.AbstractEntityEventProcessor : Processor [automatic-role-request-approval-processor] end for [AutomaticRoleRequestEvent [type: EXECUTE, content: eu.bcvsolutions.idm.core.api.dto.IdmAutomaticRoleRequestDto [code= c224d890-deef-4a13-ab74-2b3fa3028e83], properties: {checkRight=true}]] with order [-1000].
2019-03-30 13:55:15.589  INFO 10665 --- [nio-8080-exec-1] e.b.i.c.a.e.AbstractEntityEventProcessor : Processor [automatic-role-request-realization-processor] start for [AutomaticRoleRequestEvent [type: EXECUTE, content: eu.bcvsolutions.idm.core.api.dto.IdmAutomaticRoleRequestDto [code= c224d890-deef-4a13-ab74-2b3fa3028e83], properties: {checkRight=true}]] with order [0].
2019-03-30 13:55:15.669  INFO 10665 --- [nio-8080-exec-1] e.b.i.c.m.s.i.DefaultEntityEventManager  : Publishing event [RoleTreeNodeEvent [type: CREATE, content: eu.bcvsolutions.idm.core.api.dto.IdmRoleTreeNodeDto [code= null], properties: {}]]
2019-03-30 13:55:15.669  INFO 10665 --- [nio-8080-exec-1] e.b.i.c.a.e.AbstractEntityEventProcessor : Processor [role-tree-node-save-processor] start for [RoleTreeNodeEvent [type: CREATE, content: eu.bcvsolutions.idm.core.api.dto.IdmRoleTreeNodeDto [code= null], properties: {}]] with order [0].
2019-03-30 13:55:15.747  INFO 10665 --- [nio-8080-exec-1] e.b.i.c.a.e.AbstractEntityEventProcessor : Processor [role-tree-node-save-processor] end for [RoleTreeNodeEvent [type: CREATE, content: eu.bcvsolutions.idm.core.api.dto.IdmRoleTreeNodeDto [code= f5d9b706-4506-46bc-9d69-861c1a36e8ba], properties: {}]] with order [0].
2019-03-30 13:55:15.748  INFO 10665 --- [nio-8080-exec-1] e.b.i.c.m.s.i.DefaultEntityEventManager  : Event [RoleTreeNodeEvent [type: CREATE, content: eu.bcvsolutions.idm.core.api.dto.IdmRoleTreeNodeDto [code= f5d9b706-4506-46bc-9d69-861c1a36e8ba], properties: {}]] is completed
2019-03-30 13:55:16.614 ERROR 10665 --- [task-executor-2] .c.s.a.s.AbstractLongRunningTaskExecutor : [core:AUTOMATIC_ROLE_TASK_EMPTY:ab09d8c1-ed33-4e36-beed-9cb51f60d014] Automatic role id is required. ({})

eu.bcvsolutions.idm.core.api.exception.ResultCodeException: Automatic role id is required.
        at eu.bcvsolutions.idm.core.scheduler.task.impl.AddNewAutomaticRoleTaskExecutor.getRoleTreeNode(AddNewAutomaticRoleTaskExecutor.java:160)
        at eu.bcvsolutions.idm.core.scheduler.task.impl.AddNewAutomaticRoleTaskExecutor.getItemsToProcess(AddNewAutomaticRoleTaskExecutor.java:74)
        at eu.bcvsolutions.idm.core.scheduler.api.service.AbstractSchedulableStatefulExecutor.executeProcess(AbstractSchedulableStatefulExecutor.java:126)
        at eu.bcvsolutions.idm.core.scheduler.api.service.AbstractSchedulableStatefulExecutor.process(AbstractSchedulableStatefulExecutor.java:58)
        at eu.bcvsolutions.idm.core.scheduler.api.service.AbstractSchedulableStatefulExecutor.process(AbstractSchedulableStatefulExecutor.java:46)
        at eu.bcvsolutions.idm.core.scheduler.api.service.AbstractLongRunningTaskExecutor.call(AbstractLongRunningTaskExecutor.java:176)
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
        at org.springframework.security.concurrent.DelegatingSecurityContextRunnable.run(DelegatingSecurityContextRunnable.java:80)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
        at java.lang.Thread.run(Thread.java:748)

Between 13:55:15.748 and 13:55:16.614 is lots of provisioning operation.

Affected version: 8.1.7