Highlighted
Honored Contributor.
Honored Contributor.
1295 views

Mails are not sending from UserApp after IDM46 Upgrade

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=users,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=58b394f3024b4c0c888a212c7697c409&aqua=true&jsa=%7B%22approve%22%3A%22submitThenOpener%28%27approve%27%2C+3000%29%22%2C%22deny%22%3A%22submitThenOpener%28%27deny%27%2C+3000%29%22%2C%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%27claim%27%29%22%2C%22update%22%3A%22submitThenOpener%28%27update%27%2C+3000%29%22%2C%22comments%22%3A%22window.close%28%29%22%7D&rro=false'
2017-07-31 14:52:52,815 [DEBUG] DataItemEvaluator [RBPM] result: IDMProv/approvalForm.do?weId=58b394f3024b4c0c888a212c7697c409&aqua=true&jsa=%7B%22approve%22%3A%22submitThenOpener%28%27approve%27%2C+3000%29%22%2C%22deny%22%3A%22submitThenOpener%28%27deny%27%2C+3000%29%22%2C%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%27claim%27%29%22%2C%22update%22%3A%22submitThenOpener%28%27update%27%2C+3000%29%22%2C%22comments%22%3A%22window.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=58b394f3024b4c0c888a212c7697c409&aqua=true&jsa=%7B%22approve%22%3A%22submitThenOpener%28%27approve%27%2C+3000%29%22%2C%22deny%22%3A%22submitThenOpener%28%27deny%27%2C+3000%29%22%2C%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%27claim%27%29%22%2C%22update%22%3A%22submitThenOpener%28%27update%27%2C+3000%29%22%2C%22comments%22%3A%22window.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.NotificationException: Error generating URL used in template.
at com.novell.soa.af.impl.core.WorkflowNotification.send(WorkflowNotification.java:318)
at com.novell.soa.af.impl.activity.NotificationActivity.process(NotificationActivity.java:59)
at com.novell.soa.af.impl.activity.ActivityNode.notifyArrive(ActivityNode.java:206)
at com.novell.soa.af.impl.core.ProcessImpl.startActivity(ProcessImpl.java:1706)
at com.novell.soa.af.impl.core.ProcessImpl.forward(ProcessImpl.java:1603)
at com.novell.soa.af.impl.activity.ActivityNode.forward(ActivityNode.java:265)
at com.novell.soa.af.impl.activity.ActivityNode.forward(ActivityNode.java:240)
at com.novell.soa.af.impl.activity.StartActivity.process(StartActivity.java:94)
at com.novell.soa.af.impl.activity.ActivityNode.notifyArrive(ActivityNode.java:206)
at com.novell.soa.af.impl.activity.RunnableActivity.run(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.send(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=driverset1,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=driverset1,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=driverset1,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=driverset1,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=driverset1,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=driverset1,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
Labels (1)
0 Likes
7 Replies
Highlighted
Micro Focus Contributor
Micro Focus Contributor

Re: Mails are not sending from UserApp after IDM46 Upgrade

The root cause for the issue is that <mapping resource="com/novell/soa/af/impl/persist/Configuration.hbm.xml"/> was missing from the hibernate.cfg.xml

Regards,

Gerard.
0 Likes
Highlighted
Honored Contributor.
Honored Contributor.

Re: Mails are not sending from UserApp after IDM46 Upgrade

Thank you Gerard. For your quick help.

Regards
Siva ram T
0 Likes
Highlighted
Knowledge Partner
Knowledge Partner

Re: Mails are not sending from UserApp after IDM46 Upgrade

On 8/1/2017 7:54 AM, gerardma wrote:
>
> The root cause for the issue is that <mapping
> resource="com/novell/soa/af/impl/persist/Configuration.hbm.xml"/> was
> missing from the hibernate.cfg.xml


How did you figure that one out?
0 Likes
Highlighted
Absent Member.
Absent Member.

Re: Mails are not sending from UserApp after IDM46 Upgrade

geoffc;2463170 wrote:
On 8/1/2017 7:54 AM, gerardma wrote:
>
> The root cause for the issue is that <mapping
> resource="com/novell/soa/af/impl/persist/Configuration.hbm.xml"/> was
> missing from the hibernate.cfg.xml


How did you figure that one out?


Dear Geoffrey,

We could infer from debug/trace logs of appropriate api calls, provide additional details.

In my view: We suspect with below NPE(snippet from original post), seems unable to look from hibernate.cfg.xml:

Caused by: java.lang.NullPointerException
at com.novell.soa.af.impl.core.WorkflowNotification.s end(WorkflowNotification.java:313)
... 12 more
0 Likes
Highlighted
Knowledge Partner
Knowledge Partner

Re: Mails are not sending from UserApp after IDM46 Upgrade

On 8/1/2017 10:24 AM, SPSivasubramanian wrote:
>
> geoffc;2463170 Wrote:
>> On 8/1/2017 7:54 AM, gerardma wrote:
>>>
>>> The root cause for the issue is that <mapping
>>> resource="com/novell/soa/af/impl/persist/Configuration.hbm.xml"/> was
>>> missing from the hibernate.cfg.xml

>>
>> How did you figure that one out?

>
> Dear Geoffrey,
>
> We could infer from debug/trace logs of appropriate api calls, provide
> additional details.
>
> In my view: We suspect with below NPE(snippet from original post), seems
> unable to look from hibernate.cfg.xml:
>
> Caused by: java.lang.NullPointerException
> at com.novell.soa.af.impl.core.WorkflowNotification.s
> end(WorkflowNotification.java:313)
> ... 12 more


I see what you are saying. I suppose the correct config of
hibernate.cfg.xml is documented somewhere? Seems like knowing what a
properly working one SHOULD Look like would make noticing a incorrect
one more obvious.

I.e. I wish the config files were documented with examples of working
configurations. Especially if they called out elements that if missing
break the config.



0 Likes
Highlighted
Absent Member.
Absent Member.

Re: Mails are not sending from UserApp after IDM46 Upgrade

geoffc;2463177 wrote:
On 8/1/2017 10:24 AM, SPSivasubramanian wrote:
>
> geoffc;2463170 Wrote:
>> On 8/1/2017 7:54 AM, gerardma wrote:
>>>
>>> The root cause for the issue is that <mapping
>>> resource="com/novell/soa/af/impl/persist/Configuration.hbm.xml"/> was
>>> missing from the hibernate.cfg.xml

>>
>> How did you figure that one out?

>
> Dear Geoffrey,
>
> We could infer from debug/trace logs of appropriate api calls, provide
> additional details.
>
> In my view: We suspect with below NPE(snippet from original post), seems
> unable to look from hibernate.cfg.xml:
>
> Caused by: java.lang.NullPointerException
> at com.novell.soa.af.impl.core.WorkflowNotification.s
> end(WorkflowNotification.java:313)
> ... 12 more


I see what you are saying. I suppose the correct config of
hibernate.cfg.xml is documented somewhere? Seems like knowing what a
properly working one SHOULD Look like would make noticing a incorrect
one more obvious.

I.e. I wish the config files were documented with examples of working
configurations. Especially if they called out elements that if missing
break the config.


Thank you Geoffrey. Hope our Documentation team keeping eye on Forum threads, also we have requested to take this input as well to capture in troubleshooting section and other pointers. Similarly we may think of other areas such as client settings and Localization mapping from hibernate.cfg.xml if somebody sees odd behavior from UI.
0 Likes
Highlighted
Knowledge Partner
Knowledge Partner

Re: Mails are not sending from UserApp after IDM46 Upgrade

>> I.e. I wish the config files were documented with examples of working
>> configurations. Especially if they called out elements that if missing
>> break the config.

>
> Thank you Geoffrey. Hope our Documentation team keeping eye on Forum
> threads, also we have requested to take this input as well to capture in
> troubleshooting section and other pointers. Similarly we may think of
> other areas such as client settings and Localization mapping from
> hibernate.cfg.xml if somebody sees odd behavior from UI.


There are many config files, whose contents are not discussed in the
docs at all. Thus no clue what they do, officially, and how do we find
out?

Be nice to see docs or TID's or Cool Solutions on these items. I am
glad to write what I can, but I need to learn some info about them somehow.


0 Likes
The opinions expressed above are the personal opinions of the authors, not of Micro Focus. By using this site, you accept the Terms of Use and Rules of Participation. Certain versions of content ("Material") accessible here may contain branding from Hewlett-Packard Company (now HP Inc.) and Hewlett Packard Enterprise Company. As of September 1, 2017, the Material is now offered by Micro Focus, a separately owned and operated company. Any reference to the HP and Hewlett Packard Enterprise/HPE marks is historical in nature, and the HP and Hewlett Packard Enterprise/HPE marks are the property of their respective owners.