We often notice that user doesnt get assigned with a role even after successful role request creation from driver. Please find the below log from Role and Resource service driver.

[02/07/18 13:54:27.593]:Role.log ST:Start transaction.
[02/07/18 13:54:27.593]:Role.log ST:type(add-entry)entry-id(5176975) dn(\T=HONQAIGATREE\O=system\CN=DriverSet\CN=UserAp plication\CN=AppConfig\CN=RoleConfig\CN=Requests
\CN=20180207135416-1868970bb4384b0a8c9334ae7412eb2f-0) class-id(2472) class-name(nrfRequest)
[02/07/18 13:54:27.594]:Role.log ST:type(add-value)Syntax=SYNTAX_INTEGER, attributeName=nrfStatus, number=0
[02/07/18 13:54:27.594]:Role.log ST:Processing events for transaction.
[02/07/18 13:54:27.594]:Role.log ST:
<nds dtdversion="4.0" ndsversion="8.x">
<source>
<product edition="Advanced" version="4.5.5.0">DirXML</product>
<contact>NetIQ Corporation</contact>
</source>
<input>
<add cached-time="20180207135427.574Z" class-name="nrfRequest" event-id="az18u1557#20180207135427#1#2:e29bfe5c-cfdd-4d28-a1bb-5cfe9be2ddcf" qualified-src-dn="O=sys
tem\CN=DriverSet\CN=UserApplication\CN=AppConfig\C N=RoleConfig\CN=Requests\CN=20180207135416-1868970bb4384b0a8c9334ae7412eb2f-0" src-dn="\HONQAIGATREE\system\DriverSet
\UserApplication\AppConfig\RoleConfig\Requests\201 80207135416-1868970bb4384b0a8c9334ae7412eb2f-0" src-entry-id="5176975" timestamp="1518011656#13">
<add-attr attr-name="nrfStatus">
<value timestamp="1518011656#13" type="int">0</value>
</add-attr>
</add>
</input>
</nds>
[02/07/18 13:54:27.596]:Role.log ST:Applying event transformation policies.
[02/07/18 13:54:27.596]:Role.log ST:Applying policy: %+C%14CNOVLRSERVB-sub-etp%-C.
[02/07/18 13:54:27.596]:Role.log ST: Applying to add #1.
[02/07/18 13:54:27.596]:Role.log ST: Evaluating selection criteria for rule 'Ignore everything except add, modify, and sync for all classes'.
[02/07/18 13:54:27.596]:Role.log ST: (if-operation not-match "add|modify|sync") = FALSE.
[02/07/18 13:54:27.597]:Role.log ST: Rule rejected.
[02/07/18 13:54:27.597]:Role.log ST: Evaluating selection criteria for rule 'Cleanup the entitlement results for entitlements granted by NRF'.
[02/07/18 13:54:27.597]:Role.log ST: (if-operation equal "modify") = FALSE.
[02/07/18 13:54:27.597]:Role.log ST: Rule rejected.
[02/07/18 13:54:27.597]:Role.log ST: Evaluating selection criteria for rule 'Convert the event into a custom command to send to the driver'.
[02/07/18 13:54:27.597]:Role.log ST: Rule selected.
[02/07/18 13:54:27.598]:Role.log ST: Applying rule 'Convert the event into a custom command to send to the driver'.
[02/07/18 13:54:27.598]:Role.log ST: Action: do-set-local-variable("command",scope="policy",token-map("NOVLRSERVB-sub-CommandMappingTable","class-name","command",
token-class-name())).
[02/07/18 13:54:27.598]:Role.log ST: arg-string(token-map("NOVLRSERVB-sub-CommandMappingTable","class-name","command",token-class-name()))
[02/07/18 13:54:27.599]:Role.log ST: token-map("NOVLRSERVB-sub-CommandMappingTable","class-name","command",token-class-name())
[02/07/18 13:54:27.599]:Role.log ST: token-map("NOVLRSERVB-sub-CommandMappingTable","class-name","command",token-class-name())
[02/07/18 13:54:27.599]:Role.log ST: token-class-name()
[02/07/18 13:54:27.599]:Role.log ST: Token Value: "nrfRequest".
[02/07/18 13:54:27.600]:Role.log ST: Arg Value: "nrfRequest".
[02/07/18 13:54:27.600]:Role.log ST: Token Value: "nrf:request".
[02/07/18 13:54:27.600]:Role.log ST: Arg Value: "nrf:request".
[02/07/18 13:54:27.600]:Role.log ST: Action: do-append-xml-element("$command$","..").
[02/07/18 13:54:27.600]:Role.log ST: Expanded variable reference '$command$' to 'nrf:request'.
[02/07/18 13:54:27.600]:Role.log ST: Action: do-set-xml-attr("dn","../nrf:*",token-xpath("@qualified-src-dn")).
[02/07/18 13:54:27.601]:Role.log ST: arg-string(token-xpath("@qualified-src-dn"))
[02/07/18 13:54:27.601]:Role.log ST: token-xpath("@qualified-src-dn")
[02/07/18 13:54:27.601]:Role.log ST: Token Value: "O=system\CN=DriverSet\CN=UserApplication\CN=AppCo nfig\CN=RoleConfig\CN=Requests\CN=20180207135416-1868970bb4384b0a8c9334ae7412eb2f-0".
[02/07/18 13:54:27.601]:Role.log ST: Arg Value: "O=system\CN=DriverSet\CN=UserApplication\CN=AppCo nfig\CN=RoleConfig\CN=Requests\CN=20180207135416-1868970bb4384b0a8c9334ae7412eb2f-0".
[02/07/18 13:54:27.602]:Role.log ST: Action: do-if().
[02/07/18 13:54:27.602]:Role.log ST: Evaluating conditions.
[02/07/18 13:54:27.602]:Role.log ST: (if-op-attr 'nrfChildRoles' changing) = FALSE.
[02/07/18 13:54:27.602]:Role.log ST: Action: do-if().
[02/07/18 13:54:27.602]:Role.log ST: Evaluating conditions.
[02/07/18 13:54:27.602]:Role.log ST: (if-op-attr 'nrfAssignedResources' changing) = FALSE.
[02/07/18 13:54:27.602]:Role.log ST: Performing else actions.
[02/07/18 13:54:27.603]:Role.log ST: Evaluating selection criteria for rule 'Get rid of any association that might be there and veto the original event'.
[02/07/18 13:54:27.603]:Role.log ST: Rule selected.
[02/07/18 13:54:27.603]:Role.log ST: Applying rule 'Get rid of any association that might be there and veto the original event'.
[02/07/18 13:54:27.603]:Role.log ST: Action: do-if().
[02/07/18 13:54:27.603]:Role.log ST: Evaluating conditions.
[02/07/18 13:54:27.603]:Role.log ST: (if-association available) = FALSE.
[02/07/18 13:54:27.604]:Role.log ST: Performing else actions.
[02/07/18 13:54:27.604]:Role.log ST: Action: do-if().
[02/07/18 13:54:27.604]:Role.log ST: Evaluating conditions.
[02/07/18 13:54:27.604]:Role.log ST: (if-xpath true "association/@state='migrate'") = FALSE.
[02/07/18 13:54:27.604]:Role.log ST: Action: do-veto().
[02/07/18 13:54:27.604]:Role.log ST:Policy returned:
[02/07/18 13:54:27.605]:Role.log ST:
<nds dtdversion="4.0" ndsversion="8.x">
<source>
<product edition="Advanced" version="4.5.5.0">DirXML</product>
<contact>NetIQ Corporation</contact>
</source>
<input>
<nrf:request dn="O=system\CN=DriverSet\CN=UserApplication\CN=Ap pConfig\CN=RoleConfig\CN=Requests\CN=2018020713541 6-1868970bb4384b0a8c9334ae7412eb2f-0" xmlns:nrf="urn:dirxml:nrf"/>
</input>
</nds>
[02/07/18 13:54:27.605]:Role.log ST:Subscriber processing request for .
[02/07/18 13:54:27.606]:Role.log ST:Submitting unknown event to subscriber shim.
[02/07/18 13:54:27.606]:Role.log ST:No command transformation policies.
[02/07/18 13:54:27.606]:Role.log ST:Filtering out notification-only attributes.
[02/07/18 13:54:27.606]:Role.log ST:Fixing up association references.
[02/07/18 13:54:27.606]:Role.log ST:No schema mapping policies.
[02/07/18 13:54:27.606]:Role.log ST:No output transformation policies.
[02/07/18 13:54:27.606]:Role.log ST:Submitting document to subscriber shim:
[02/07/18 13:54:27.607]:Role.log ST:
<nds dtdversion="4.0" ndsversion="8.x">
<source>
<product edition="Advanced" version="4.5.5.0">DirXML</product>
<contact>NetIQ Corporation</contact>
</source>
<input>
<nrf:request dn="O=system\CN=DriverSet\CN=UserApplication\CN=Ap pConfig\CN=RoleConfig\CN=Requests\CN=2018020713541 6-1868970bb4384b0a8c9334ae7412eb2f-0" event-id="0" xmlns:nrf="urn:dirxml:nrf"/>
</input>
</nds>
[02/07/18 13:54:27.609]:Role.log ST:: Processing request
DN: O=system\CN=DriverSet\CN=UserApplication\CN=AppCon fig\CN=RoleConfig\CN=Requests\CN=20180207135416-1868970bb4384b0a8c9334ae7412eb2f-0
[02/07/18 13:54:27.632]:Role.log ST:: Process Equivalent To Me
Role: Process Equivalent To Me
Role: O=system\CN=DriverSet\CN=UserApplication\CN=AppCon fig\CN=RoleConfig\CN=RoleDefs\CN=Level20\CN=QGLOBA L01ADAccount
Operation: 5
Identity: O=honeywell\OU=Users\CN=H190941
Operation: {1}
Identity: {2}
[02/07/18 13:54:27.644]:Role.log ST:: Retrieving resource association objects based on a role or resource DN. DN: O=system\CN=DriverSet\CN=UserApplication\CN=AppCon fig\CN=RoleConfig\CN=RoleDefs\CN=Level20\CN=QGLOBA L01ADAccount
[02/07/18 13:54:27.816]:Role.log ST:: Processing request
DN: O=system\CN=DriverSet\CN=UserApplication\CN=AppCon fig\CN=RoleConfig\CN=ResourceRequests\CN=201802071 35427-69c1c66d6a9143868fcc01b00dd24950-0
[02/07/18 13:54:27.839]:Role.log ST:SubscriptionShim.execute() returned:
[02/07/18 13:54:27.839]:Role.log ST:
<nds dtdversion="4.0">
<source>
<product instance="Role and Resource Service Driver" version="4.5.0.3">NetIQ Role Service Driver</product>
<contact>NetIQ Corporation</contact>
</source>
<output>
<status event-id="0" level="success">Transitioned request status from 0 to 30
DN: O=system\CN=DriverSet\CN=UserApplication\CN=AppCon fig\CN=RoleConfig\CN=Requests\CN=20180207135416-1868970bb4384b0a8c9334ae7412eb2f-0</status>
<status event-id="0" level="success">Added assigned role to identity
Role: O=system\CN=DriverSet\CN=UserApplication\CN=AppCon fig\CN=RoleConfig\CN=RoleDefs\CN=Level20\CN=QGLOBA L01ADAccount
Identity: O=honeywell\OU=Users\CN=H190941</status>
<status event-id="0" level="success">Created resource request
DN: O=system\CN=DriverSet\CN=UserApplication\CN=AppCon fig\CN=RoleConfig\CN=ResourceRequests\CN=201802071 35427-69c1c66d6a9143868fcc01b00dd24950-0</status>
<status event-id="0" level="success">Transitioned request status from 0 to 30
DN: O=system\CN=DriverSet\CN=UserApplication\CN=AppCon fig\CN=RoleConfig\CN=ResourceRequests\CN=201802071 35427-69c1c66d6a9143868fcc01b00dd24950-0</status>
<status event-id="0" level="success">Transitioned request status from 30 to 30
DN: O=system\CN=DriverSet\CN=UserApplication\CN=AppCon fig\CN=RoleConfig\CN=ResourceRequests\CN=201802071 35427-69c1c66d6a9143868fcc01b00dd24950-0</status>
<status event-id="0" level="success">Added assigned resource to user
Resource: O=system\CN=DriverSet\CN=UserApplication\CN=AppCon fig\CN=RoleConfig\CN=ResourceDefs\CN=QGLOBAL01Acco unt
User: O=honeywell\OU=Users\CN=H190941</status>
<status event-id="0" level="success">Transitioned request status from 30 to 50
DN: O=system\CN=DriverSet\CN=UserApplication\CN=AppCon fig\CN=RoleConfig\CN=ResourceRequests\CN=201802071 35427-69c1c66d6a9143868fcc01b00dd24950-0</status>
<status event-id="0" level="success">Transitioned request status from 30 to 50
DN: O=system\CN=DriverSet\CN=UserApplication\CN=AppCon fig\CN=RoleConfig\CN=Requests\CN=20180207135416-1868970bb4384b0a8c9334ae7412eb2f-0</status>
</output>
</nds>
[02/07/18 13:54:27.841]:Role.log ST:No input transformation policies.
[02/07/18 13:54:27.842]:Role.log ST:No schema mapping policies.
[02/07/18 13:54:27.842]:Role.log ST:Resolving association references.
[02/07/18 13:54:27.842]:Role.log ST:Processing returned document.
[02/07/18 13:54:27.842]:Role.log ST:Processing operation <status> for .
[02/07/18 13:54:27.842]:Role.log ST:
DirXML Log Event -------------------
Driver: \HONQAIGATREE\system\DriverSet\Role and Resource Service Driver
Channel: Subscriber
Status: Success
Message: Transitioned request status from 0 to 30
DN: O=system\CN=DriverSet\CN=UserApplication\CN=AppCon fig\CN=RoleConfig\CN=Requests\CN=20180207135416-1868970bb4384b0a8c9334ae7412eb2f-0
[02/07/18 13:54:27.843]:Role.log ST:Processing operation <status> for .
[02/07/18 13:54:27.843]:Role.log ST:
DirXML Log Event -------------------
Driver: \HONQAIGATREE\system\DriverSet\Role and Resource Service Driver
Channel: Subscriber
Status: Success
Message: Added assigned role to identity
Role: O=system\CN=DriverSet\CN=UserApplication\CN=AppCon fig\CN=RoleConfig\CN=RoleDefs\CN=Level20\CN=QGLOBA L01ADAccount
Identity: O=honeywell\OU=Users\CN=H190941
[02/07/18 13:54:27.844]:Role.log ST:Processing operation <status> for .
[02/07/18 13:54:27.844]:Role.log ST:
DirXML Log Event -------------------
Driver: \HONQAIGATREE\system\DriverSet\Role and Resource Service Driver
Channel: Subscriber
Status: Success
Message: Created resource request
DN: O=system\CN=DriverSet\CN=UserApplication\CN=AppCon fig\CN=RoleConfig\CN=ResourceRequests\CN=201802071 35427-69c1c66d6a9143868fcc01b00dd24950-0
[02/07/18 13:54:27.844]:Role.log ST:Processing operation <status> for .
[02/07/18 13:54:27.845]:Role.log ST:
DirXML Log Event -------------------
Driver: \HONQAIGATREE\system\DriverSet\Role and Resource Service Driver
Channel: Subscriber
Status: Success
Message: Transitioned request status from 0 to 30
DN: O=system\CN=DriverSet\CN=UserApplication\CN=AppCon fig\CN=RoleConfig\CN=ResourceRequests\CN=201802071 35427-69c1c66d6a9143868fcc01b00dd24950-0
[02/07/18 13:54:27.845]:Role.log ST:Processing operation <status> for .
[02/07/18 13:54:27.845]:Role.log ST:
DirXML Log Event -------------------
Driver: \HONQAIGATREE\system\DriverSet\Role and Resource Service Driver
Channel: Subscriber
Status: Success
Message: Transitioned request status from 30 to 30
DN: O=system\CN=DriverSet\CN=UserApplication\CN=AppCon fig\CN=RoleConfig\CN=ResourceRequests\CN=201802071 35427-69c1c66d6a9143868fcc01b00dd24950-0
[02/07/18 13:54:27.846]:Role.log ST:Processing operation <status> for .
[02/07/18 13:54:27.846]:Role.log ST:
DirXML Log Event -------------------
Driver: \HONQAIGATREE\system\DriverSet\Role and Resource Service Driver
Channel: Subscriber
Status: Success
Message: Added assigned resource to user
Resource: O=system\CN=DriverSet\CN=UserApplication\CN=AppCon fig\CN=RoleConfig\CN=ResourceDefs\CN=QGLOBAL01Acco unt
User: O=honeywell\OU=Users\CN=H190941
[02/07/18 13:54:27.847]:Role.log ST:Processing operation <status> for .
[02/07/18 13:54:27.847]:Role.log ST:
DirXML Log Event -------------------
Driver: \HONQAIGATREE\system\DriverSet\Role and Resource Service Driver
Channel: Subscriber
Status: Success
Message: Transitioned request status from 30 to 50
DN: O=system\CN=DriverSet\CN=UserApplication\CN=AppCon fig\CN=RoleConfig\CN=ResourceRequests\CN=201802071 35427-69c1c66d6a9143868fcc01b00dd24950-0
[02/07/18 13:54:27.847]:Role.log ST:Processing operation <status> for .
[02/07/18 13:54:27.847]:Role.log ST:
DirXML Log Event -------------------
Driver: \HONQAIGATREE\system\DriverSet\Role and Resource Service Driver
Channel: Subscriber
Status: Success
Message: Transitioned request status from 30 to 50
DN: O=system\CN=DriverSet\CN=UserApplication\CN=AppCon fig\CN=RoleConfig\CN=Requests\CN=20180207135416-1868970bb4384b0a8c9334ae7412eb2f-0
[02/07/18 13:54:27.848]:Role.log ST:End transaction.