Workflow not starting

[Hassan]

Hi Erman

I have problem with workflow and error message is below...Do you have any for the troubleshoot steps ?

LOG_ID_UNKNOWN : oracle.apps.fnd.cp.gsc.SvcComponentContainer.getNewWorkflowContext() : BEGIN
LOG_ID_UNKNOWN : oracle.apps.fnd.cp.gsc.SvcComponentContainer.loadGlobalParameters() : BEGIN
LOG_ID_UNKNOWN : oracle.apps.fnd.cp.gsm.GSMSvcComponentContainer.loadContainerParameters() : BEGIN
LOG_ID_UNKNOWN : oracle.apps.fnd.cp.gsc.SvcComponentContainer.start() : Successfully retrieved global and container parameters
LOG_ID_UNKNOWN : oracle.apps.fnd.cp.gsc.SvcComponentContainer.validateParameterValues(Properties) : BEGIN
LOG_ID_UNKNOWN : oracle.apps.fnd.cp.gsc.SvcComponentContainer.validateParameterValues(Properties) : ({SVC_COMP_MAX_ERROR_COUNT=10, SVC_COMP_MONITOR_LOOP_SLEEP=60, SVC_CONTAINER_LOOP_SLEEP=10, SVC_CONTAINER_READ_TIMEOUT=10, SVC_COMP_MONITOR_ONDEMAND_FREQ=300, SVC_CONTAINER_LOG_LEVEL=4, SVC_WRITE_DIAG_TO_GSM_LOG=Y})
LOG_ID_UNKNOWN : oracle.apps.fnd.cp.gsc.SvcComponentContainer.start() : Successfully validated container parameters
LOG_ID_UNKNOWN : oracle.apps.fnd.cp.gsm.GSMSvcComponentContainer.loadDetails(Connection) : BEGIN (oracle.jdbc.driver.T4CConnection@172975c)
LOG_ID_UNKNOWN : oracle.apps.fnd.cp.gsc.SvcComponentContainer.start() : Successfully retrieved container details
LOG_ID_UNKNOWN : oracle.apps.fnd.cp.gsm.GSMSvcComponentContainer.startLogger() : BEGIN
LOG_ID_UNKNOWN : oracle.apps.fnd.cp.gsc.SvcComponentContainer.startLogger() : BEGIN [default implementation]
Could not start Service Component Container -> oracle.apps.fnd.cp.gsc.SvcComponentContainerException: BES system could not establish connection to the control queue after 180 seconds
oracle.apps.fnd.cp.gsc.SvcComponentContainerException: BES system could not establish connection to the control queue after 180 seconds
        at oracle.apps.fnd.cp.gsc.SvcComponentContainer.startBusinessEventListener(SvcComponentContainer.java:1601)
        at oracle.apps.fnd.cp.gsc.SvcComponentContainer.start(SvcComponentContainer.java:855)
        at oracle.apps.fnd.cp.gsm.GSMSvcComponentContainer.main(GSMSvcComponentContainer.java:424)
33.119:71656:1432713049605:3:EXCEPTION:[SVC-GSM-WFMLRSVC-16057 : oracle.apps.fnd.cp.gsc.Logger.setLevel(int)]:BEGIN(4)
[May 27, 2015 10:50:49 AM EEST]:1432713049625:-1:-1:erpdev:172.30.33.119:-1:-1:-1:-1:ANONYMOUS(-1):-1:Thread[main,5,main]:172.30.33.119:71656:1432713049605:3:PROCEDURE:[fnd.wf.apps.WorkflowContext]:oracle.apps.fnd.wf.apps.WorkflowContext.getJDBCConnection(): BEGIN/END
[May 27, 2015 10:50:49 AM EEST]:1432713049626:-1:-1:erpdev:172.30.33.119:-1:-1:-1:-1:ANONYMOUS(-1):-1:Thread[main,5,main]:172.30.33.119:71656:1432713049605:3:PROCEDURE:[fnd.wf.apps.WorkflowContext]:oracle.apps.fnd.wf.apps.WorkflowContext.getJDBCConnection(null,0x1ef666,false,false,false): : BEGIN
[May 27, 2015 10:50:49 AM EEST]:1432713049626:-1:-1:erpdev:172.30.33.119:-1:-1:-1:-1:ANONYMOUS(-1):-1:Thread[main,5,main]:172.30.33.119:71656:1432713049605:3:PROCEDURE:[fnd.wf.apps.WorkflowContext]:oracle.apps.fnd.wf.apps.WorkflowContext.getJDBCConnection: END: returning oracle.jdbc.driver.T4CConnection@1a7f06
[May 27, 2015 10:50:49 AM EEST]:1432713049626:-1:-1:erpdev:172.30.33.119:-1:-1:-1:-1:ANONYMOUS(-1):-1:Thread[main,5,main]:172.30.33.119:71656:1432713049605:3:PROCEDURE:[fnd.wf.apps.WorkflowContext]:oracle.apps.fnd.wf.apps.WorkflowContext.getJDBCConnection(): BEGIN/END
[May 27, 2015 10:50:49 AM EEST]:1432713049626:-1:-1:erpdev:172.30.33.119:-1:-1:-1:-1:ANONYMOUS(-1):-1:Thread[main,5,main]:172.30.33.119:71656:1432713049605:3:PROCEDURE:[fnd.wf.apps.WorkflowContext]:oracle.apps.fnd.wf.apps.WorkflowContext.getJDBCConnection(null,0x1ef666,false,false,false): : BEGIN
[May 27, 2015 10:50:49 AM EEST]:1432713049626:-1:-1:erpdev:172.30.33.119:-1:-1:-1:-1:ANONYMOUS(-1):-1:Thread[main,5,main]:172.30.33.119:71656:1432713049605:3:PROCEDURE:[fnd.wf.apps.WorkflowContext]:oracle.apps.fnd.wf.apps.WorkflowContext.getJDBCConnection: END: returning oracle.jdbc.driver.T4CConnection@1a7f06
[May 27, 2015 10:50:49 AM EEST]:1432713049627:-1:-1:erpdev:172.30.33.119:-1:-1:-1:-1:ANONYMOUS(-1):-1:Thread[main,5,main]:172.30.33.119:71656:1432713049605:3:PROCEDURE:[fnd.security.AppsConnectionManager]:appsInitialize(int,int,int,int,Connection,int,int,int): BEGIN
[May 27, 2015 10:50:49 AM EEST]:1432713049630:-1:-1:erpdev:172.30.33.119:-1:-1:-1:-1:ANONYMOUS(-1):-1:Thread[main,5,main]:172.30.33.119:71656:1432713049605:3:PROCEDURE:[fnd.security.AppsConnectionManager]:appsInitialize(int,int,int,int,Connection,int,int,int): END
[May 27, 2015 10:50:49 AM EEST]:1432713049630:-1:-1:erpdev:172.30.33.119:-1:-1:1:20420:SYSADMIN(0):-1:Thread[main,5,main]:172.30.33.119:71656:1432713049605:3:PROCEDURE:[fnd.profiles.Profiles]:getProfileOptionValue:  name=ORG_ID; levelID=10003; levelValue=20420; levelValueApplID=1
[May 27, 2015 10:50:49 AM EEST]:1432713049631:-1:-1:erpdev:172.30.33.119:-1:-1:1:20420:SYSADMIN(0):-1:Thread[main,5,main]:172.30.33.119:71656:1432713049605:3:PROCEDURE:[fnd.profiles.Profiles]:getProfileOptionValue:  name=ORG_ID; levelID=10001; levelValue=0; levelValueApplID=0
[May 27, 2015 10:50:49 AM EEST]:1432713049634:-1:-1:erpdev:172.30.33.119:-1:-1:1:20420:SYSADMIN(0):-1:Thread[main,5,main]:172.30.33.119:71656:1432713049605:3:EXCEPTION:[SVC-GSM-WFMLRSVC-16057 : oracle.apps.fnd.cp.gsc.SvcComponentContainer.start()]:Successfully created new component control context
[May 27, 2015 10:50:49 AM EEST]:1432713049640:-1:-1:erpdev:172.30.33.119:-1:-1:1:20420:SYSADMIN(0):-1:Thread[main,5,main]:172.30.33.119:71656:1432713049605:3:EXCEPTION:[SVC-GSM-WFMLRSVC-16057 : oracle.apps.fnd.cp.gsc.SvcComponentContainer.startBusinessEventListener()]:Successfully asked for BES control connection to be established (asynchronous)
[May 27, 2015 10:50:49 AM EEST]:1432713049643:-1:-1:erpdev:172.30.33.119:-1:-1:1:20420:SYSADMIN(0):-1:Thread[main,5,main]:172.30.33.119:71656:1432713049605:3:EXCEPTION:[SVC-GSM-WFMLRSVC-16057 : oracle.apps.fnd.cp.gsc.SvcComponentContainer.startBusinessEventListener()]:Registered a subscription for oracle.apps.fnd.cp.gsc.SvcComponent.refresh
[May 27, 2015 10:50:49 AM EEST]:1432713049643:-1:-1:erpdev:172.30.33.119:-1:-1:1:20420:SYSADMIN(0):-1:Thread[main,5,main]:172.30.33.119:71656:1432713049605:3:EXCEPTION:[SVC-GSM-WFMLRSVC-16057 : oracle.apps.fnd.cp.gsc.SvcComponentContainer.startBusinessEventListener()]:Registered a subscription for oracle.apps.fnd.cp.gsc.SvcComponent.resume
[May 27, 2015 10:50:49 AM EEST]:1432713049644:-1:-1:erpdev:172.30.33.119:-1:-1:1:20420:SYSADMIN(0):-1:Thread[main,5,main]:172.30.33.119:71656:1432713049605:3:EXCEPTION:[SVC-GSM-WFMLRSVC-16057 : oracle.apps.fnd.cp.gsc.SvcComponentContainer.startBusinessEventListener()]:Registered a subscription for oracle.apps.fnd.cp.gsc.SvcComponent.start
[May 27, 2015 10:50:49 AM EEST]:1432713049644:-1:-1:erpdev:172.30.33.119:-1:-1:1:20420:SYSADMIN(0):-1:Thread[main,5,main]:172.30.33.119:71656:1432713049605:3:EXCEPTION:[SVC-GSM-WFMLRSVC-16057 : oracle.apps.fnd.cp.gsc.SvcComponentContainer.startBusinessEventListener()]:Registered a subscription for oracle.apps.fnd.cp.gsc.SvcComponent.stop
[May 27, 2015 10:50:49 AM EEST]:1432713049644:-1:-1:erpdev:172.30.33.119:-1:-1:1:20420:SYSADMIN(0):-1:Thread[main,5,main]:172.30.33.119:71656:1432713049605:3:EXCEPTION:[SVC-GSM-WFMLRSVC-16057 : oracle.apps.fnd.cp.gsc.SvcComponentContainer.startBusinessEventListener()]:Registered a subscription for oracle.apps.fnd.cp.gsc.SvcComponent.suspend
[May 27, 2015 10:50:49 AM EEST]:1432713049644:-1:-1:erpdev:172.30.33.119:-1:-1:1:20420:SYSADMIN(0):-1:Thread[main,5,main]:172.30.33.119:71656:1432713049605:3:EXCEPTION:[SVC-GSM-WFMLRSVC-16057 : oracle.apps.fnd.cp.gsc.SvcComponentContainer.startBusinessEventListener()]:Registered a subscription for oracle.apps.fnd.wf.mailer.Mailer.notification.summary
[May 27, 2015 10:50:49 AM EEST]:1432713049645:-1:-1:erpdev:172.30.33.119:-1:-1:1:20420:SYSADMIN(0):-1:Thread[main,5,main]:172.30.33.119:71656:1432713049605:3:EXCEPTION:[SVC-GSM-WFMLRSVC-16057 : oracle.apps.fnd.cp.gsc.SvcComponentContainer.startBusinessEventListener()]:Have waited 0 seconds for BES system to establish connection to the control queue.  Waiting another 2 seconds
[May 27, 2015 10:50:49 AM EEST]:1432713049690:-1:-1:erpdev:172.30.33.119:-1:-1:1:20420:SYSADMIN(0):-1:Thread[Task Request Thread,5,main]:172.30.33.119:71656:1432713049691:4:UNEXPECTED:[fnd.wf.bes.ConnectionManager$1]:Failed to establish Java Business Event System control connection: databaseId = DEV, ownerName = APPLSYS, queueName = WF_CONTROL, subscriberName = WFBESCL_5360, selector = null, exception = oracle.apps.fnd.wf.bes.BusinessEventException: A JMS error occurred; see the linked exception for details., linkedException = oracle.jms.AQjmsException: ORA-00900: invalid SQL statement

[May 27, 2015 10:50:51 AM EEST]:1432713051645:-1:-1:erpdev:172.30.33.119:-1:-1:1:20420:SYSADMIN(0):-1:Thread[main,5,main]:172.30.33.119:71656:1432713049605:3:EXCEPTION:[SVC-GSM-WFMLRSVC-16057 : oracle.apps.fnd.cp.gsc.SvcComponentContainer.startBusinessEventListener()]:Have waited 2 seconds for BES system to establish connection to the control queue.  Waiting another 2 seconds
[May 27, 2015 10:50:53 AM EEST]:1432713053645:-1:-1:erpdev:172.30.33.119:-1:-1:1:20420:SYSADMIN(0):-1:Thread[main,5,main]:172.30.33.119:71656:1432713049605:3:EXCEPTION:[SVC-GSM-WFMLRSVC-16057 : oracle.apps.fnd.cp.gsc.SvcComponentContainer.startBusinessEventListener()]:Have waited 4 seconds for BES system to establish connection to the control queue.  Waiting another 2 seconds
[May 27, 2015 10:50:55 AM EEST]:1432713055646:-1:-1:erpdev:172.30.33.119:-1:-1:1:20420:SYSADMIN(0):-1:Thread[main,5,main]:172.30.33.119:71656:1432713049605:3:EXCEPTION:[SVC-GSM-WFMLRSVC-16057 : oracle.apps.fnd.cp.gsc.SvcComponentContainer.startBusinessEventListener()]:Have waited 6 seconds for BES system to establish connection to the control queue.  Waiting another 2 seconds
[May 27, 2015 10:50:57 AM EEST]:1432713057646:-1:-1:erpdev:172.30.33.119:-1:-1:1:20420:SYSADMIN(0):-1:Thread[main,5,main]:172.30.33.119:71656:1432713049605:3:EXCEPTION:[SVC-GSM-WFMLRSVC-16057 : oracle.apps.fnd.cp.gsc.SvcComponentContainer.startBusinessEventListener()]:Have waited 8 seconds for BES system to establish connection to the control queue.  Waiting another 2 seconds

[ErmanArslansOracleBlog]

Hi Hassan,

I see the env is a DEV, so it is probably a clone and this was not working before..
Anyways;

Reference : Oracle Support:
Cloning an Oracle Applications instance will not update the host and instance-specific information used by Oracle Workflow. Review the tables and columns listed in Table 4 to check for any instance-specific data in the Workflow configuration on the target system.
Table 4: Workflow configuration settings

So please check and update the things below if necessary;

Table Name Column Name Column Value Details
WF_NOTIFICATION_ATTRIBUTES TEXT_VALUE Value starts with http://[old web host]: Update to new web host.
WF_ITEM_ATTRIBUTE_VALUES TEXT_VALUE Value starts with "http://[old web host]: Update to new web host.
WF_SYSTEMS GUID Using the Workflow Administrator Web Applications responsibility, create a new system defined as the new global database name.
WF_SYSTEMS NAME Replace value with the database global name.
WF_AGENTS ADDRESS Update database link with the new database global name.
FND_FORM_FUNCTIONS WEB_HOST_NAME Update with the new web host name.
FND_FORM_FUNCTIONS WEB_AGENT_NAME Update to point at the new PL/SQL listener name.
FND_CONCURRENT_REQUESTS LOGFILE_NAME Update with the correct path to the logfile directory.
FND_CONCURRENT_REQUESTS OUTFILE_NAME Update with the new directory path on the target system.

[ErmanArslansOracleBlog]

If it is 12.2, recreate the queue as mentined in "Workflow Services Are Not Starting, Error "BES system could not establish connection to the control queue after 180 seconds" (Doc ID 1663093.1)"

1. Stop the Concurrent Managers.
2. Drop/Recreate queue WF_CONTROL:

sqlplus apps/<apps_pwd> @$FND_TOP/patch/115/sql/wfctqrec.sql APPLSYS <applsys_pwd>

3. Start the Concurrent Managers.
4. Verify the issue.

[Hassan]

I have did what you suggest but the error is the same.
There is also another error in the log file ;

[May 27, 2015 2:00:32 PM EEST]:1432724432656:-1:-1:erpdev:172.30.33.119:-1:-1:1:20420:SYSADMIN(0):-1:Thread[main,5,main]:172.30.33.119:78327:1432724432613:3:EXCEPTION:[SVC-GSM-WFMLRSVC-16190 : oracle.apps.fnd.cp.gsc.SvcComponentContainer.startBusinessEventListener()]:Registered a subscription for oracle.apps.fnd.wf.mailer.Mailer.notification.summary
[May 27, 2015 2:00:32 PM EEST]:1432724432656:-1:-1:erpdev:172.30.33.119:-1:-1:1:20420:SYSADMIN(0):-1:Thread[main,5,main]:172.30.33.119:78327:1432724432613:3:EXCEPTION:[SVC-GSM-WFMLRSVC-16190 : oracle.apps.fnd.cp.gsc.SvcComponentContainer.startBusinessEventListener()]:Have waited 0 seconds for BES system to establish connection to the control queue.  Waiting another 2 seconds
[May 27, 2015 2:00:32 PM EEST]:1432724432706:-1:-1:erpdev:172.30.33.119:-1:-1:1:20420:SYSADMIN(0):-1:Thread[Task Request Thread,5,main]:172.30.33.119:78327:1432724432706:4:UNEXPECTED:[fnd.wf.bes.ConnectionManager$1]:Failed to establish Java Business Event System control connection: databaseId = DEV, ownerName = APPLSYS, queueName = WF_CONTROL, subscriberName = WFBESCL_5423, selector = null, exception = oracle.apps.fnd.wf.bes.BusinessEventException: A JMS error occurred; see the linked exception for details., linkedException = oracle.jms.AQjmsException: ORA-00900: invalid SQL statement