Hi,

We have upgraded our production environment with IDM 4.6 from IDM 4.5.5. After this from any workflow mails are triggering we could see the below error from catalina.out



2017-07-31 14:52:52,737 [DEBUG] EngineImpl [RBPM] startProcess() process id =cn=Reset password - NEW,cn=RequestDefs,cn=AppConfig,cn=UserApplication ,cn=driverset1,o=system
2017-07-31 14:52:52,737 [TRACE] ProcessImpl [RBPM] start() requestId=58b394f3024b4c0c888a212c7697c409, type=cn=Reset password - NEW,cn=RequestDefs,cn=AppConfig,cn=UserApplication ,cn=driverset1,o=system
2017-07-31 14:52:52,737 [TRACE] Link [RBPM] Link() FORWARD[start, Activity]
2017-07-31 14:52:52,752 [DEBUG] DataItemEvaluator [RBPM] evaluating target expression: wi.createXPath("flow-data/start/request_form/txtMobile").setValue("")
2017-07-31 14:52:52,752 [DEBUG] DataItemEvaluator [RBPM] result: com.novell.soa.script.mozilla.javascript.Undefined @3c0cce2e
2017-07-31 14:52:52,752 [DEBUG] DataItemEvaluator [RBPM] evaluating target expression: wi.createXPath("flow-data/start/request_form/comments").setValue("test")
2017-07-31 14:52:52,752 [DEBUG] DataItemEvaluator [RBPM] result: com.novell.soa.script.mozilla.javascript.Undefined @3c0cce2e
2017-07-31 14:52:52,752 [DEBUG] DataItemEvaluator [RBPM] evaluating target expression: wi.createXPath("flow-data/start/request_form/txtRecipient").setValue("Z8MST")
2017-07-31 14:52:52,752 [DEBUG] DataItemEvaluator [RBPM] result: com.novell.soa.script.mozilla.javascript.Undefined @3c0cce2e
2017-07-31 14:52:52,752 [DEBUG] DataItemEvaluator [RBPM] evaluating target expression: wi.createXPath("flow-data/start/request_form/txtuserdn").setValue("Z8MST")
2017-07-31 14:52:52,752 [DEBUG] DataItemEvaluator [RBPM] result: com.novell.soa.script.mozilla.javascript.Undefined @3c0cce2e
2017-07-31 14:52:52,752 [DEBUG] DataItemEvaluator [RBPM] evaluating target expression: wi.createXPath("flow-data/start/request_form/txtEmail").setValue("zrm@BMK.dk")
2017-07-31 14:52:52,752 [DEBUG] DataItemEvaluator [RBPM] result: com.novell.soa.script.mozilla.javascript.Undefined @3c0cce2e
2017-07-31 14:52:52,752 [DEBUG] DataItemEvaluator [RBPM] evaluating target expression: wi.createXPath("flow-data/start/request_form/txtInitiator").setValue("uaadmin")
2017-07-31 14:52:52,752 [DEBUG] DataItemEvaluator [RBPM] result: com.novell.soa.script.mozilla.javascript.Undefined @3c0cce2e
2017-07-31 14:52:52,752 [DEBUG] DataItemEvaluator [RBPM] evaluating target expression: wi.createXPath("flow-data/start/request_form/recipientinitials").setValue("MST")
2017-07-31 14:52:52,752 [DEBUG] DataItemEvaluator [RBPM] result: com.novell.soa.script.mozilla.javascript.Undefined @3c0cce2e
2017-07-31 14:52:52,752 [DEBUG] DataItemEvaluator [RBPM] evaluating target expression: wi.createXPath("flow-data/start/request_form/radiochoice").setValue("")
2017-07-31 14:52:52,752 [DEBUG] DataItemEvaluator [RBPM] result: com.novell.soa.script.mozilla.javascript.Undefined @3c0cce2e
2017-07-31 14:52:52,752 [DEBUG] DataItemEvaluator [RBPM] evaluating target expression: wi.createXPath("flow-data/start/request_form/txtpassword").setValue("Z844v8wC")
2017-07-31 14:52:52,752 [DEBUG] DataItemEvaluator [RBPM] result: com.novell.soa.script.mozilla.javascript.Undefined @3c0cce2e
2017-07-31 14:52:52,752 [DEBUG] DataItemEvaluator [RBPM] evaluating target expression: wi.createXPath("flow-data/start/request_form/initiatepassword").setValue("")
2017-07-31 14:52:52,752 [DEBUG] DataItemEvaluator [RBPM] result: com.novell.soa.script.mozilla.javascript.Undefined @3c0cce2e
2017-07-31 14:52:52,752 [DEBUG] DataItemEvaluator [RBPM] evaluating target expression: wi.createXPath("flow-data/start/request_form/txtfieldLocale").setValue("EN")
2017-07-31 14:52:52,752 [DEBUG] DataItemEvaluator [RBPM] result: com.novell.soa.script.mozilla.javascript.Undefined @3c0cce2e
2017-07-31 14:52:52,752 [DEBUG] DataItemEvaluator [RBPM] evaluating target expression: wi.createXPath("flow-data/start/request_form/txtOther").setValue("st00374756@techmahindra.com")
2017-07-31 14:52:52,752 [DEBUG] DataItemEvaluator [RBPM] result: com.novell.soa.script.mozilla.javascript.Undefined @3c0cce2e
2017-07-31 14:52:52,752 [DEBUG] DataItemEvaluator [RBPM] evaluating target expression: wi.createXPath("flow-data/start/request_form/pwdpolicy").setValue("CN=BMK_PWD_Users,CN=Password Policies,CN=Security")
2017-07-31 14:52:52,768 [DEBUG] DataItemEvaluator [RBPM] result: com.novell.soa.script.mozilla.javascript.Undefined @3c0cce2e
2017-07-31 14:52:52,768 [DEBUG] DataItemEvaluator [RBPM] evaluating target expression: wi.createXPath("flow-data/start/request_form/cprnumber").setValue("1911709991")
2017-07-31 14:52:52,768 [DEBUG] DataItemEvaluator [RBPM] result: com.novell.soa.script.mozilla.javascript.Undefined @3c0cce2e
2017-07-31 14:52:52,768 [DEBUG] DataItemEvaluator [RBPM] evaluating target expression: wi.createXPath("flow-data/start/request_form/txtUser").setValue("cn=Z8MST,ou=contractors,ou=use rs,o=BMK")
2017-07-31 14:52:52,768 [DEBUG] DataItemEvaluator [RBPM] result: com.novell.soa.script.mozilla.javascript.Undefined @3c0cce2e
2017-07-31 14:52:52,768 [DEBUG] DataItemEvaluator [RBPM] evaluating target expression: wi.createXPath("flow-data/IDM_COMPLETED_APPROVAL_STATUS").setValue("denied")
2017-07-31 14:52:52,768 [DEBUG] DataItemEvaluator [RBPM] result: com.novell.soa.script.mozilla.javascript.Undefined @3c0cce2e
2017-07-31 14:52:52,768 [DEBUG] DataItemEvaluator [RBPM] evaluating target expression: wi.createXPath("flow-data/start/request_form/txtLastName").setValue("SAPIPV003")
2017-07-31 14:52:52,768 [DEBUG] DataItemEvaluator [RBPM] result: com.novell.soa.script.mozilla.javascript.Undefined @3c0cce2e
2017-07-31 14:52:52,768 [DEBUG] DataItemEvaluator [RBPM] evaluating target expression: wi.createXPath("flow-data/start/request_form/txtFirstName").setValue("Test MST IPV 003")
2017-07-31 14:52:52,768 [DEBUG] DataItemEvaluator [RBPM] result: com.novell.soa.script.mozilla.javascript.Undefined @3c0cce2e
2017-07-31 14:52:52,768 [TRACE] ActivityNode [RBPM] persistStatus() activity=Reset password[start], newStatus=Arriving
2017-07-31 14:52:52,768 [TRACE] ProcessImpl [RBPM] startActivity() activity=Reset password[start], newThread=true, pooled = true
2017-07-31 14:52:52,768 [TRACE] ActivityNode [RBPM] notifyArrive(), activity=Reset password[start]
2017-07-31 14:52:52,768 [TRACE] ActivityNode [RBPM] process(), activity=Reset password[start]
2017-07-31 14:52:52,768 [TRACE] ActivityNode [RBPM] persistStatus() activity=Reset password[start], newStatus=Running
2017-07-31 14:52:52,783 [INFO] LogEvent [RBPM] [Workflow_Started] Initiated by cn=uaadmin,ou=sa,o=BMK, Process ID: 58b394f3024b4c0c888a212c7697c409, Process Name: cn=Reset password - NEW,cn=RequestDefs,cn=AppConfig,cn=UserApplication ,cn=driverset1,o=system:199, Activity: start, Recipient: cn=uaadmin,ou=sa,o=BMK, Secondary User: null
2017-07-31 14:52:52,783 [TRACE] ActivityNode [RBPM] forward(), activity=Reset password[start], link=FORWARD[start, Activity]
2017-07-31 14:52:52,783 [TRACE] Link [RBPM] Link() FORWARD[Activity, Activity2]
2017-07-31 14:52:52,783 [TRACE] ProcessImpl [RBPM] forward() current=Reset password[start], next=E-Mail[Activity]
2017-07-31 14:52:52,799 [TRACE] ActivityNode [RBPM] persistStatus() activity=Reset password[start], newStatus=Departing
2017-07-31 14:52:52,799 [TRACE] ActivityNode [RBPM] notifyDepart(), activity=Reset password[start]
2017-07-31 14:52:52,799 [TRACE] ActivityNode [RBPM] applyChanges() activity=Reset password[start]
2017-07-31 14:52:52,799 [TRACE] ActivityNode [RBPM] persistStatus() activity=Reset password[start], newStatus=Idle
2017-07-31 14:52:52,799 [TRACE] ActivityNode [RBPM] persistStatus() activity=E-Mail[Activity], newStatus=Arriving
2017-07-31 14:52:52,799 [INFO] LogEvent [RBPM] [Workflow_Forwarded] Initiated by System, Process ID: 58b394f3024b4c0c888a212c7697c409, Process Name: cn=Reset password - NEW,cn=RequestDefs,cn=AppConfig,cn=UserApplication ,cn=driverset1,o=system:199, Activity: start, Recipient: cn=uaadmin,ou=sa,o=BMK
2017-07-31 14:52:52,799 [TRACE] ProcessImpl [RBPM] startActivity() activity=E-Mail[Activity], newThread=false, pooled = false
2017-07-31 14:52:52,799 [TRACE] ActivityNode [RBPM] notifyArrive(), activity=E-Mail[Activity]
2017-07-31 14:52:52,799 [TRACE] ActivityNode [RBPM] process(), activity=E-Mail[Activity]
2017-07-31 14:52:52,799 [TRACE] ActivityNode [RBPM] persistStatus() activity=E-Mail[Activity], newStatus=Running
2017-07-31 14:52:52,815 [TRACE] DataItemEvaluator [RBPM] registerNrfRequest - Started
2017-07-31 14:52:52,815 [TRACE] DataItemEvaluator [RBPM] registerNrfRequest - Done
2017-07-31 14:52:52,815 [TRACE] DataItemEvaluator [RBPM] registerNrfResourceRequest - Started
2017-07-31 14:52:52,815 [TRACE] DataItemEvaluator [RBPM] registerNrfResourceRequest - Done
2017-07-31 14:52:52,815 [TRACE] DataItemEvaluator [RBPM] registerAttestation - Started
2017-07-31 14:52:52,815 [TRACE] DataItemEvaluator [RBPM] registerAttestation - Done
2017-07-31 14:52:52,815 [TRACE] WorkflowNotification [RBPM] Trying to evaluate mapping target:[TASK_DETAILS]
2017-07-31 14:52:52,815 [DEBUG] DataItemEvaluator [RBPM] evaluating to string: 'IDMProv/approvalForm.do?weId=58b394f3024b4c0c888a212c7697c 409&aqua=true&jsa=%7B%22approve%22%3A%22submitThen Opener%28%27approve%27%2C+3000%29%22%2C%22deny%22% 3A%22submitThenOpener%28%27deny%27%2C+3000%29%22%2 C%22refuse%22%3A%22submitThenOpener%28%27refuse%27 %2C+3000%29%22%2C%22cancel%22%3A%22window.close%28 %29%22%2C%22claim%22%3A%22submitOnlyAction%28%27cl aim%27%29%22%2C%22update%22%3A%22submitThenOpener% 28%27update%27%2C+3000%29%22%2C%22comments%22%3A%2 2window.close%28%29%22%7D&rro=false'
2017-07-31 14:52:52,815 [DEBUG] DataItemEvaluator [RBPM] result: IDMProv/approvalForm.do?weId=58b394f3024b4c0c888a212c7697c 409&aqua=true&jsa=%7B%22approve%22%3A%22submitThen Opener%28%27approve%27%2C+3000%29%22%2C%22deny%22% 3A%22submitThenOpener%28%27deny%27%2C+3000%29%22%2 C%22refuse%22%3A%22submitThenOpener%28%27refuse%27 %2C+3000%29%22%2C%22cancel%22%3A%22window.close%28 %29%22%2C%22claim%22%3A%22submitOnlyAction%28%27cl aim%27%29%22%2C%22update%22%3A%22submitThenOpener% 28%27update%27%2C+3000%29%22%2C%22comments%22%3A%2 2window.close%28%29%22%7D&rro=false
2017-07-31 14:52:52,815 [TRACE] WorkflowNotification [RBPM] Expression [TASK_DETAILS] evaluated to:[IDMProv/approvalForm.do?weId=58b394f3024b4c0c888a212c7697c 409&aqua=true&jsa=%7B%22approve%22%3A%22submitThen Opener%28%27approve%27%2C+3000%29%22%2C%22deny%22% 3A%22submitThenOpener%28%27deny%27%2C+3000%29%22%2 C%22refuse%22%3A%22submitThenOpener%28%27refuse%27 %2C+3000%29%22%2C%22cancel%22%3A%22window.close%28 %29%22%2C%22claim%22%3A%22submitOnlyAction%28%27cl aim%27%29%22%2C%22update%22%3A%22submitThenOpener% 28%27update%27%2C+3000%29%22%2C%22comments%22%3A%2 2window.close%28%29%22%7D&rro=false]
2017-07-31 14:52:52,815 [TRACE] WorkflowNotification [RBPM] Trying to evaluate mapping target:[TASKLIST_CONTEXT]
2017-07-31 14:52:52,815 [DEBUG] DataItemEvaluator [RBPM] evaluating to string: 'idmdash/#/tasks'
2017-07-31 14:52:52,815 [DEBUG] DataItemEvaluator [RBPM] result: idmdash/#/tasks
2017-07-31 14:52:52,815 [TRACE] WorkflowNotification [RBPM] Expression [TASKLIST_CONTEXT] evaluated to:[idmdash/#/tasks]
2017-07-31 14:52:52,815 [TRACE] WorkflowNotification [RBPM] Trying to evaluate mapping target:[NOTIFICATION_TEMPLATE]
2017-07-31 14:52:52,815 [DEBUG] DataItemEvaluator [RBPM] evaluating to string: 'cn=Notify-ResetPassword-Test,cn=Default Notification Collection,cn=Security'
2017-07-31 14:52:52,815 [DEBUG] DataItemEvaluator [RBPM] result: cn=Notify-ResetPassword-Test,cn=Default Notification Collection,cn=Security
2017-07-31 14:52:52,815 [TRACE] WorkflowNotification [RBPM] Expression [NOTIFICATION_TEMPLATE] evaluated to:[cn=Notify-ResetPassword-Test,cn=Default Notification Collection,cn=Security]
2017-07-31 14:52:52,815 [TRACE] WorkflowNotification [RBPM] Trying to evaluate mapping target:[TO]
2017-07-31 14:52:52,815 [DEBUG] DataItemEvaluator [RBPM] 'zrm@BMK.dk'
2017-07-31 14:52:52,815 [ERROR] LogEvent [RBPM] [Workflow_Error] Initiated by cn=uaadmin,ou=sa,o=BMK, Error Message: Error generating URL used in template., Process ID: 58b394f3024b4c0c888a212c7697c409, Process Name: cn=Reset password - NEW,cn=RequestDefs,cn=AppConfig,cn=UserApplication ,cn=driverset1,o=system:199, Activity: Activity, Recipient: cn=uaadmin,ou=sa,o=BMK
2017-07-31 14:52:52,815 [TRACE] LogEvent [RBPM] Error generating URL used in template.
com.novell.soa.notification.impl.NotificationExcep tion: Error generating URL used in template.
at com.novell.soa.af.impl.core.WorkflowNotification.s end(WorkflowNotification.java:318)
at com.novell.soa.af.impl.activity.NotificationActivi ty.process(NotificationActivity.java:59)
at com.novell.soa.af.impl.activity.ActivityNode.notif yArrive(ActivityNode.java:206)
at com.novell.soa.af.impl.core.ProcessImpl.startActiv ity(ProcessImpl.java:1706)
at com.novell.soa.af.impl.core.ProcessImpl.forward(Pr ocessImpl.java:1603)
at com.novell.soa.af.impl.activity.ActivityNode.forwa rd(ActivityNode.java:265)
at com.novell.soa.af.impl.activity.ActivityNode.forwa rd(ActivityNode.java:240)
at com.novell.soa.af.impl.activity.StartActivity.proc ess(StartActivity.java:94)
at com.novell.soa.af.impl.activity.ActivityNode.notif yArrive(ActivityNode.java:206)
at com.novell.soa.af.impl.activity.RunnableActivity.r un(RunnableActivity.java:50)
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:745)
Caused by: java.lang.NullPointerException
at com.novell.soa.af.impl.core.WorkflowNotification.s end(WorkflowNotification.java:313)
... 12 more
2017-07-31 14:52:52,815 [TRACE] ActivityNode [RBPM] forward(), activity=E-Mail[Activity], link=FORWARD[Activity, Activity2]
2017-07-31 14:52:52,815 [TRACE] Link [RBPM] Link() FORWARD[Activity2, Activity4]
2017-07-31 14:52:52,815 [TRACE] ProcessImpl [RBPM] forward() current=E-Mail[Activity], next=Log[Activity2]
2017-07-31 14:52:52,815 [TRACE] ActivityNode [RBPM] persistStatus() activity=E-Mail[Activity], newStatus=Departing
2017-07-31 14:52:52,815 [TRACE] ActivityNode [RBPM] notifyDepart(), activity=E-Mail[Activity]
2017-07-31 14:52:52,815 [TRACE] ActivityNode [RBPM] applyChanges() activity=E-Mail[Activity]
2017-07-31 14:52:52,815 [TRACE] ActivityNode [RBPM] persistStatus() activity=E-Mail[Activity], newStatus=Idle
2017-07-31 14:52:52,815 [TRACE] ActivityNode [RBPM] persistStatus() activity=Log[Activity2], newStatus=Arriving
2017-07-31 14:52:52,830 [INFO] LogEvent [RBPM] [Workflow_Forwarded] Initiated by System, Process ID: 58b394f3024b4c0c888a212c7697c409, Process Name: cn=Reset password - NEW,cn=RequestDefs,cn=AppConfig,cn=UserApplication ,cn=driverset1,o=system:199, Activity: Activity, Recipient: cn=uaadmin,ou=sa,o=BMK
2017-07-31 14:52:52,830 [TRACE] ProcessImpl [RBPM] startActivity() activity=Log[Activity2], newThread=false, pooled = false
2017-07-31 14:52:52,830 [TRACE] ActivityNode [RBPM] notifyArrive(), activity=Log[Activity2]
2017-07-31 14:52:52,830 [TRACE] ActivityNode [RBPM] process(), activity=Log[Activity2]
2017-07-31 14:52:52,830 [TRACE] ActivityNode [RBPM] persistStatus() activity=Log[Activity2], newStatus=Running
2017-07-31 14:52:52,830 [DEBUG] DataItemEvaluator [RBPM] evaluating to string: GCV.get('IDMAdminEmail')
2017-07-31 14:52:52,830 [TRACE] GCVScript [RBPM] Calling extended operation (GetDriverGCVResponse) lCtx.extendedOperation(new GetDriverGCVRequest(cn=UserApplication,cn=driverse t1,o=system, IDMAdminEmail));
2017-07-31 14:52:53,002 [TRACE] GCVScript [RBPM] GCV definition is: null
2017-07-31 14:52:53,002 [DEBUG] DataItemEvaluator [RBPM] result: null
2017-07-31 14:52:53,002 [DEBUG] DataItemEvaluator [RBPM] evaluating to string: GCV.get('IDMAdminEmail')
2017-07-31 14:52:53,002 [TRACE] GCVScript [RBPM] Calling extended operation (GetDriverGCVResponse) lCtx.extendedOperation(new GetDriverGCVRequest(cn=UserApplication,cn=driverse t1,o=system, IDMAdminEmail));
2017-07-31 14:52:53,190 [TRACE] GCVScript [RBPM] GCV definition is: null
2017-07-31 14:52:53,190 [DEBUG] DataItemEvaluator [RBPM] result: null
2017-07-31 14:52:53,190 [INFO] LogActivity [RBPM] [User_Message] Initiated by cn=uaadmin,ou=sa,o=BMK, Process ID: 58b394f3024b4c0c888a212c7697c409, Process Name: cn=Reset password - NEW,cn=RequestDefs,cn=AppConfig,cn=UserApplication ,cn=driverset1,o=system:199, Activity: Activity2, Recipient: cn=uaadmin,ou=sa,o=BMK, Author: , Message:
2017-07-31 14:52:53,190 [DEBUG] DataItemEvaluator [RBPM] evaluating to string: GCV.get('IDMAdminEmail')
2017-07-31 14:52:53,190 [TRACE] GCVScript [RBPM] Calling extended operation (GetDriverGCVResponse) lCtx.extendedOperation(new GetDriverGCVRequest(cn=UserApplication,cn=driverse t1,o=system, IDMAdminEmail));
2017-07-31 14:52:53,268 [INFO] VirtualDataDefinition [RBPM] Directory Abstraction Query Definitions have been loaded into server lifetime memory
2017-07-31 14:52:53,362 [TRACE] GCVScript [RBPM] GCV definition is: null
2017-07-31 14:52:53,362 [DEBUG] DataItemEvaluator [RBPM] result: null
2017-07-31 14:52:53,362 [TRACE] ActivityNode [RBPM] forward(), activity=Log[Activity2], link=FORWARD[Activity2, Activity4]
2017-07-31 14:52:53,362 [TRACE] Link [RBPM] Link() FORWARD[Activity4, Activity3]
2017-07-31 14:52:53,362 [TRACE] ProcessImpl [RBPM] forward() current=Log[Activity2], next=Log 1[Activity4]
2017-07-31 14:52:53,362 [TRACE] ActivityNode [RBPM] persistStatus() activity=Log[Activity2], newStatus=Departing
2017-07-31 14:52:53,362 [TRACE] ActivityNode [RBPM] notifyDepart(), activity=Log[Activity2]
2017-07-31 14:52:53,362 [TRACE] ActivityNode [RBPM] persistStatus() activity=Log[Activity2], newStatus=Idle
2017-07-31 14:52:53,362 [TRACE] ActivityNode [RBPM] persistStatus() activity=Log 1[Activity4], newStatus=Arriving
2017-07-31 14:52:53,362 [INFO] LogEvent [RBPM] [Workflow_Forwarded] Initiated by System, Process ID: 58b394f3024b4c0c888a212c7697c409, Process Name: cn=Reset password - NEW,cn=RequestDefs,cn=AppConfig,cn=UserApplication ,cn=driverset1,o=system:199, Activity: Activity2, Recipient: cn=uaadmin,ou=sa,o=BMK
2017-07-31 14:52:53,377 [TRACE] ProcessImpl [RBPM] startActivity() activity=Log 1[Activity4], newThread=false, pooled = false
2017-07-31 14:52:53,377 [TRACE] ActivityNode [RBPM] notifyArrive(), activity=Log 1[Activity4]
2017-07-31 14:52:53,377 [TRACE] ActivityNode [RBPM] process(), activity=Log 1[Activity4]
2017-07-31 14:52:53,377 [TRACE] ActivityNode [RBPM] persistStatus() activity=Log 1[Activity4], newStatus=Running
2017-07-31 14:52:53,377 [DEBUG] DataItemEvaluator [RBPM] evaluating to string: GCV.get('idmAdminEmail')
2017-07-31 14:52:53,377 [TRACE] GCVScript [RBPM] Calling extended operation (GetDriverGCVResponse) lCtx.extendedOperation(new GetDriverGCVRequest(cn=UserApplication,cn=driverse t1,o=system, idmAdminEmail));
2017-07-31 14:52:53,549 [TRACE] GCVScript [RBPM] GCV definition is: <?xml version="1.0" encoding="UTF-8"?><nds dtdversion="4.0" ndsversion="8.x">
<source>
<product edition="Advanced" version="4.6.0.0">DirXML</product>
<contact>NetIQ Corporation</contact>
</source>
<output>
<status level="success">Global Configuration Values<configuration-values>
<definitions>
<definition display-name="IDM Administrators Email" name="idmAdminEmail" type="string">
<description>Useful for tracking exceptions that break a fixed policy or flow</description>
<value xml:space="preserve">IDM.Test@colesmyer.com.au</value>
</definition>
</definitions>
</configuration-values>
</status>
</output>
</nds>
2017-07-31 14:52:53,549 [DEBUG] DataItemEvaluator [RBPM] result: IDM.Test@colesmyer.com.au
2017-07-31 14:52:53,549 [DEBUG] DataItemEvaluator [RBPM] evaluating to string: GCV.get('idmAdminEmail')
2017-07-31 14:52:53,549 [TRACE] GCVScript [RBPM] Calling extended operation (GetDriverGCVResponse) lCtx.extendedOperation(new GetDriverGCVRequest(cn=UserApplication,cn=driverse t1,o=system, idmAdminEmail));
2017-07-31 14:52:53,721 [TRACE] GCVScript [RBPM] GCV definition is: <?xml version="1.0" encoding="UTF-8"?><nds dtdversion="4.0" ndsversion="8.x">
<source>
<product edition="Advanced" version="4.6.0.0">DirXML</product>
<contact>NetIQ Corporation</contact>
</source>
<output>
<status level="success">Global Configuration Values<configuration-values>
<definitions>
<definition display-name="IDM Administrators Email" name="idmAdminEmail" type="string">
<description>Useful for tracking exceptions that break a fixed policy or flow</description>
<value xml:space="preserve">IDM.Test@colesmyer.com.au</value>
</definition>
</definitions>
</configuration-values>
</status>
</output>
</nds>
2017-07-31 14:52:53,721 [DEBUG] DataItemEvaluator [RBPM] result: IDM.Test@colesmyer.com.au
2017-07-31 14:52:53,721 [INFO] LogActivity [RBPM] [User_Message] Initiated by cn=uaadmin,ou=sa,o=BMK, Process ID: 58b394f3024b4c0c888a212c7697c409, Process Name: cn=Reset password - NEW,cn=RequestDefs,cn=AppConfig,cn=UserApplication ,cn=driverset1,o=system:199, Activity: Activity4, Recipient: cn=uaadmin,ou=sa,o=BMK, Author: IDM.Test@colesmyer.com.au, Message: IDM.Test@colesmyer.com.au
2017-07-31 14:52:53,721 [DEBUG] DataItemEvaluator [RBPM] evaluating to string: GCV.get('idmAdminEmail')
2017-07-31 14:52:53,721 [TRACE] GCVScript [RBPM] Calling extended operation (GetDriverGCVResponse) lCtx.extendedOperation(new GetDriverGCVRequest(cn=UserApplication,cn=driverse t1,o=system, idmAdminEmail));
2017-07-31 14:52:53,908 [TRACE] GCVScript [RBPM] GCV definition is: <?xml version="1.0" encoding="UTF-8"?><nds dtdversion="4.0" ndsversion="8.x">
<source>
<product edition="Advanced" version="4.6.0.0">DirXML</product>
<contact>NetIQ Corporation</contact>
</source>
<output>
<status level="success">Global Configuration Values<configuration-values>
<definitions>
<definition display-name="IDM Administrators Email" name="idmAdminEmail" type="string">
<description>Useful for tracking exceptions that break a fixed policy or flow</description>
<value xml:space="preserve">IDM.Test@colesmyer.com.au</value>
</definition>
</definitions>
</configuration-values>
</status>
</output>
</nds>
2017-07-31 14:52:53,908 [DEBUG] DataItemEvaluator [RBPM] result: IDM.Test@colesmyer.com.au
2017-07-31 14:52:53,908 [TRACE] ActivityNode [RBPM] forward(), activity=Log 1[Activity4], link=FORWARD[Activity4, Activity3]
2017-07-31 14:52:53,908 [TRACE] Link [RBPM] Link() FORWARD[Activity3, Activity1]
2017-07-31 14:52:53,908 [TRACE] ProcessImpl [RBPM] forward() current=Log 1[Activity4], next=Password reset activity[Activity3]
2017-07-31 14:52:53,908 [TRACE] ActivityNode [RBPM] persistStatus() activity=Log 1[Activity4], newStatus=Departing
2017-07-31 14:52:53,908 [TRACE] ActivityNode [RBPM] notifyDepart(), activity=Log 1[Activity4]
2017-07-31 14:52:53,924 [TRACE] ActivityNode [RBPM] persistStatus() activity=Log 1[Activity4], newStatus=Idle
2017-07-31 14:52:53,924 [TRACE] ActivityNode [RBPM] persistStatus() activity=Password reset activity[Activity3], newStatus=Arriving
2017-07-31 14:52:53,924 [INFO] LogEvent [RBPM] [Workflow_Forwarded] Initiated by System, Process ID: 58b394f3024b4c0c888a212c7697c409, Process Name: cn=Reset password - NEW,cn=RequestDefs,cn=AppConfig,cn=UserApplication ,cn=driverset1,o=system:199, Activity: Activity4, Recipient: cn=uaadmin,ou=sa,o=BMK
2017-07-31 14:52:53,924 [TRACE] ProcessImpl [RBPM] startActivity() activity=Password reset activity[Activity3], newThread=false, pooled = false
2017-07-31 14:52:53,924 [TRACE] ActivityNode [RBPM] notifyArrive(), activity=Password reset activity[Activity3]
2017-07-31 14:52:53,924 [TRACE] ActivityNode [RBPM] process(), activity=Password reset activity[Activity3]
2017-07-31 14:52:53,924 [TRACE] ActivityNode [RBPM] persistStatus() activity=Password reset activity[Activity3], newStatus=Running
2017-07-31 14:52:53,924 [DEBUG] DataItemEvaluator [RBPM] evaluating source expression: flowdata.getObject('start/request_form/txtUser')
//flowdata.get('start/request_form/recipient')
2017-07-31 14:52:53,924 [DEBUG] DataItemEvaluator [RBPM] result: [[txtUser: null]]
2017-07-31 14:52:53,924 [DEBUG] DataItemEvaluator [RBPM] evaluating source expression: 'TRUE'
2017-07-31 14:52:53,924 [DEBUG] DataItemEvaluator [RBPM] result: TRUE
2017-07-31 14:52:53,924 [DEBUG] DataItemEvaluator [RBPM] evaluating source expression: 'FALSE'
2017-07-31 14:52:53,924 [DEBUG] DataItemEvaluator [RBPM] result: FALSE
2017-07-31 14:52:53,924 [DEBUG] DataItemEvaluator [RBPM] evaluating source expression: '0'
2017-07-31 14:52:53,924 [DEBUG] DataItemEvaluator [RBPM] result: 0
2017-07-31 14:52:53,924 [DEBUG] DataItemEvaluator [RBPM] evaluating source expression: ''
2017-07-31 14:52:53,924 [DEBUG] DataItemEvaluator [RBPM] result:
2017-07-31 14:52:53,924 [DEBUG] DataItemEvaluator [RBPM] evaluating source expression: flowdata.get('start/request_form/pwdpolicy')
2017-07-31 14:52:53,924 [DEBUG] DataItemEvaluator [RBPM] result: CN=BMK_PWD_Users,CN=Password Policies,CN=Security
2017-07-31 14:52:53,924 [DEBUG] DataItemEvaluator [RBPM] evaluating source expression: flowdata.get('start/request_form/txtpassword')
2017-07-31 14:52:53,924 [DEBUG] DataItemEvaluator [RBPM] result: Z844v8wC
2017-07-31 14:52:53,924 [TRACE] ProvisionRequest [RBPM] Provision Request: Entity = [UserPasswordChange] Identifier = [cn=Z8MST,ou=contractors,ou=users,o=BMK]
Attributes = [BMKADUnLock=[TRUE] multivalued=[false] operation=[1]LockedByIntruder=[FALSE] multivalued=[false] operation=[1]LoginIntruderAttempts=[0] multivalued=[false] operation=[1]LoginIntruderResetTime=[] multivalued=[false] operation=[1]nspmPasswordPolicyDN=[CN=BMK_PWD_Users,CN=Password Policies,CN=Security] multivalued=[false] operation=[1]userPassword=[Z844v8wC] multivalued=[false] operation=[1]]
2017-07-31 14:52:53,940 [TRACE] ProvisionRequest [RBPM] updating entity: entity = [<?xml version="1.0" encoding="UTF-8"?><entity editable="false" hidden="false" removable="false">
<key>cn=Z8MST,ou=contractors,ou=users,o=BMK</key>
<display-label>cn=Z8MST,ou=contractors,ou=users,o=BMK</display-label>
<attributes>
<attribute editable="false" hidden="false" multivalue="false">
<key>BMKADUnLock</key>
<value>TRUE</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>userPassword</key>
<value>Z844v8wC</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>LoginIntruderAttempts</key>
<value>0</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>nspmPasswordPolicyDN</key>
<value>CN=BMK_PWD_Users,CN=Password Policies,CN=Security</value>
</attribute>
<attribute editable="false" hidden="false" multivalue="false">
<key>LockedByIntruder</key>
<value>FALSE</value>
</attribute>
</attributes>
</entity>

2017-07-31 14:52:54,143 [TRACE] ProvisionRequest [RBPM] VDM object modified. Object = cn=Z8MST,ou=contractors,ou=users,o=BMK
2017-07-31 14:52:54,143 [INFO] LogEvent [RBPM] [Provision_Granted] Initiated by System, Process ID: 58b394f3024b4c0c888a212c7697c409, Process Name: cn=Reset password - NEW,cn=RequestDefs,cn=AppConfig,cn=UserApplication ,cn=driverset1,o=system:199, Activity: Activity3, Recipient: cn=Z8MST,ou=contractors,ou=users,o=BMK
2017-07-31 14:52:54,143 [TRACE] ActivityNode [RBPM] forward(), activity=Password reset activity[Activity3], link=FORWARD[Activity3, Activity1]
2017-07-31 14:52:54,143 [TRACE] Link [RBPM] Link() FORWARD[Activity1, finish]
2017-07-31 14:52:54,143 [TRACE] ProcessImpl [RBPM] forward() current=Password reset activity[Activity3], next=Workflow Status[Activity1]
2017-07-31 14:52:54,143 [TRACE] ActivityNode [RBPM] persistStatus() activity=Password reset activity[Activity3], newStatus=Departing
2017-07-31 14:52:54,143 [TRACE] ActivityNode [RBPM] notifyDepart(), activity=Password reset activity[Activity3]
2017-07-31 14:52:54,143 [TRACE] StartActivity [RBPM] applyChanges() activity=Password reset activity[Activity3]
2017-07-31 14:52:54,143 [DEBUG] DataItemEvaluator [RBPM] evaluating target expression: wi.createXPath("flow-data/IDM_COMPLETED_APPROVAL_STATUS").setValue("approved ")
2017-07-31 14:52:54,143 [DEBUG] DataItemEvaluator [RBPM] result: com.novell.soa.script.mozilla.javascript.Undefined @3c0cce2e
2017-07-31 14:52:54,143 [TRACE] ActivityNode [RBPM] persistStatus() activity=Password reset activity[Activity3], newStatus=Idle
2017-07-31 14:52:54,143 [TRACE] ActivityNode [RBPM] persistStatus() activity=Workflow Status[Activity1], newStatus=Arriving
2017-07-31 14:52:54,158 [INFO] LogEvent [RBPM] [Workflow_Forwarded] Initiated by System, Process ID: 58b394f3024b4c0c888a212c7697c409, Process Name: cn=Reset password - NEW,cn=RequestDefs,cn=AppConfig,cn=UserApplication ,cn=driverset1,o=system:199, Activity: Activity3, Recipient: cn=uaadmin,ou=sa,o=BMK
2017-07-31 14:52:54,158 [TRACE] ProcessImpl [RBPM] startActivity() activity=Workflow Status[Activity1], newThread=false, pooled = false
2017-07-31 14:52:54,158 [TRACE] ActivityNode [RBPM] notifyArrive(), activity=Workflow Status[Activity1]
2017-07-31 14:52:54,158 [TRACE] ActivityNode [RBPM] process(), activity=Workflow Status[Activity1]
2017-07-31 14:52:54,158 [TRACE] ActivityNode [RBPM] persistStatus() activity=Workflow Status[Activity1], newStatus=Running
2017-07-31 14:52:54,158 [DEBUG] DataItemEvaluator [RBPM] evaluating source expression: 'approved'
2017-07-31 14:52:54,158 [DEBUG] DataItemEvaluator [RBPM] result: approved
2017-07-31 14:52:54,158 [TRACE] ActivityNode [RBPM] forward(), activity=Workflow Status[Activity1], link=FORWARD[Activity1, finish]
2017-07-31 14:52:54,158 [TRACE] ProcessImpl [RBPM] forward() current=Workflow Status[Activity1], next=Finish[finish]
2017-07-31 14:52:54,158 [TRACE] ActivityNode [RBPM] persistStatus() activity=Workflow Status[Activity1], newStatus=Departing
2017-07-31 14:52:54,158 [TRACE] ActivityNode [RBPM] notifyDepart(), activity=Workflow Status[Activity1]
2017-07-31 14:52:54,158 [TRACE] ActivityNode [RBPM] applyChanges() activity=Workflow Status[Activity1]
2017-07-31 14:52:54,158 [DEBUG] DataItemEvaluator [RBPM] evaluating target expression: wi.createXPath("flow-data/IDM_COMPLETED_APPROVAL_STATUS").setValue("approved ")
2017-07-31 14:52:54,158 [DEBUG] DataItemEvaluator [RBPM] result: com.novell.soa.script.mozilla.javascript.Undefined @3c0cce2e
2017-07-31 14:52:54,158 [DEBUG] ActivityNode [RBPM] Flushing Hibernate Session
2017-07-31 14:52:54,158 [TRACE] ActivityNode [RBPM] persistStatus() activity=Workflow Status[Activity1], newStatus=Idle
2017-07-31 14:52:54,158 [TRACE] ActivityNode [RBPM] persistStatus() activity=Finish[finish], newStatus=Arriving
2017-07-31 14:52:54,174 [INFO] LogEvent [RBPM] [Workflow_Forwarded] Initiated by System, Process ID: 58b394f3024b4c0c888a212c7697c409, Process Name: cn=Reset password - NEW,cn=RequestDefs,cn=AppConfig,cn=UserApplication ,cn=driverset1,o=system:199, Activity: Activity1, Recipient: cn=uaadmin,ou=sa,o=BMK
2017-07-31 14:52:54,174 [TRACE] ProcessImpl [RBPM] startActivity() activity=Finish[finish], newThread=false, pooled = false
2017-07-31 14:52:54,174 [TRACE] ActivityNode [RBPM] notifyArrive(), activity=Finish[finish]
2017-07-31 14:52:54,174 [TRACE] ActivityNode [RBPM] process(), activity=Finish[finish]
2017-07-31 14:52:54,174 [TRACE] ActivityNode [RBPM] persistStatus() activity=Finish[finish], newStatus=Running
2017-07-31 14:52:54,174 [DEBUG] DataItemEvaluator [RBPM] evaluating to string: flowdata.get('IDM_COMPLETED_APPROVAL_STATUS')
2017-07-31 14:52:54,174 [DEBUG] DataItemEvaluator [RBPM] result: approved
2017-07-31 14:52:54,174 [DEBUG] DataItemEvaluator [RBPM] evaluating to string: flowdata.get('IDM_COMPLETED_APPROVAL_STATUS')
2017-07-31 14:52:54,174 [DEBUG] DataItemEvaluator [RBPM] result: approved
2017-07-31 14:52:54,190 [INFO] LogEvent [RBPM] [Workflow_Ended] Initiated by System, Process ID: 58b394f3024b4c0c888a212c7697c409, Process Name: cn=Reset password - NEW,cn=RequestDefs,cn=AppConfig,cn=UserApplication ,cn=driverset1,o=system:199, Activity: finish, Recipient: cn=uaadmin,ou=sa,o=BMK

Thanks
Sivaram T